builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1474140582.19 results: success (0) buildid: 20160917093048 builduid: 4d5cd54173d94be2b857b7f5dfdb86c8 revision: ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.192955) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.193442) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.193767) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.233555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.233877) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 12:29:42-- 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% 221M=0s 2016-09-17 12:29:43 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130337 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.632026) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.632368) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019725 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:42.690135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 12:29:42.690515) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 --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=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 12:29:43,482 truncating revision to first 12 chars 2016-09-17 12:29:43,482 Setting DEBUG logging. 2016-09-17 12:29:43,483 attempt 1/10 2016-09-17 12:29:43,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/ef3c1ce0c1fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 12:29:45,010 unpacking tar archive at: autoland-ef3c1ce0c1fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.853907 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 12:29:44.585494) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:44.585868) ========= script_repo_revision: ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:44.586333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:44.586661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:44.598011) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-17 12:29:44.598322) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:29:45 INFO - MultiFileLogger online at 20160917 12:29:45 in /builds/slave/test 12:29:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 12:29:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:29:45 INFO - {'append_to_log': False, 12:29:45 INFO - 'base_work_dir': '/builds/slave/test', 12:29:45 INFO - 'blob_upload_branch': 'Autoland', 12:29:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:29:45 INFO - 'branch': 'Autoland', 12:29:45 INFO - 'buildbot_json_path': 'buildprops.json', 12:29:45 INFO - 'config_files': ('talos/linux_config.py',), 12:29:45 INFO - 'default_actions': ('clobber', 12:29:45 INFO - 'read-buildbot-config', 12:29:45 INFO - 'download-and-extract', 12:29:45 INFO - 'populate-webroot', 12:29:45 INFO - 'create-virtualenv', 12:29:45 INFO - 'install', 12:29:45 INFO - 'run-tests'), 12:29:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:29:45 INFO - 'download_minidump_stackwalk': True, 12:29:45 INFO - 'download_symbols': 'ondemand', 12:29:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:29:45 INFO - 'tooltool.py': '/tools/tooltool.py', 12:29:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:29:45 INFO - '/tools/misc-python/virtualenv.py')}, 12:29:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:29:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:29:45 INFO - 'installer_path': 'installer.exe', 12:29:45 INFO - 'log_level': 'info', 12:29:45 INFO - 'log_name': 'talos', 12:29:45 INFO - 'log_to_console': True, 12:29:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:29:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:29:45 INFO - 'opt_config_files': (), 12:29:45 INFO - 'pip_index': False, 12:29:45 INFO - 'sps_profile': False, 12:29:45 INFO - 'sps_profile_interval': 0, 12:29:45 INFO - 'suite': 'dromaeojs', 12:29:45 INFO - 'system_bits': '32', 12:29:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:29:45 INFO - 'title': 'talos-linux64-ix-001', 12:29:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:29:45 INFO - 'use_talos_json': True, 12:29:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:29:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:29:45 INFO - 'work_dir': 'build'} 12:29:45 INFO - [mozharness: 2016-09-17 19:29:45.465622Z] Running clobber step. 12:29:45 INFO - Running pre-action listener: _resource_record_pre_action 12:29:45 INFO - Running main action method: clobber 12:29:45 INFO - rmtree: /builds/slave/test/build 12:29:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:29:46 INFO - Running post-action listener: _resource_record_post_action 12:29:46 INFO - [mozharness: 2016-09-17 19:29:46.445685Z] Finished clobber step (success) 12:29:46 INFO - [mozharness: 2016-09-17 19:29:46.445785Z] Running read-buildbot-config step. 12:29:46 INFO - Running pre-action listener: _resource_record_pre_action 12:29:46 INFO - Running main action method: read_buildbot_config 12:29:46 INFO - Using buildbot properties: 12:29:46 INFO - { 12:29:46 INFO - "project": "", 12:29:46 INFO - "product": "firefox", 12:29:46 INFO - "script_repo_revision": "production", 12:29:46 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 12:29:46 INFO - "repository": "", 12:29:46 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 12:29:46 INFO - "buildid": "20160917093048", 12:29:46 INFO - "pgo_build": "True", 12:29:46 INFO - "basedir": "/builds/slave/test", 12:29:46 INFO - "buildnumber": 211, 12:29:46 INFO - "slavename": "talos-linux64-ix-001", 12:29:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:29:46 INFO - "platform": "ubuntu64_hw", 12:29:46 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 12:29:46 INFO - "branch": "autoland", 12:29:46 INFO - "repo_path": "integration/autoland", 12:29:46 INFO - "revision": "ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0", 12:29:46 INFO - "stage_platform": "linux64-pgo", 12:29:46 INFO - "builduid": "4d5cd54173d94be2b857b7f5dfdb86c8", 12:29:46 INFO - "slavebuilddir": "test" 12:29:46 INFO - } 12:29:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:29:46 INFO - Running post-action listener: _resource_record_post_action 12:29:46 INFO - [mozharness: 2016-09-17 19:29:46.448264Z] Finished read-buildbot-config step (success) 12:29:46 INFO - [mozharness: 2016-09-17 19:29:46.448355Z] Running download-and-extract step. 12:29:46 INFO - Running pre-action listener: _resource_record_pre_action 12:29:46 INFO - Running main action method: download_and_extract 12:29:46 INFO - mkdir: /builds/slave/test/build/tests 12:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:46 INFO - trying https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:46 INFO - Downloading https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:29:47 INFO - Downloaded 1361 bytes. 12:29:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:47 INFO - Using the following test package requirements: 12:29:47 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:29:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:29:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'jsshell-linux-x86_64.zip'], 12:29:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:29:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:29:47 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:29:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:29:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:47 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:29:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:29:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:29:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:47 INFO - Fetch https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:29:47 INFO - Expected file size: 21936275 12:29:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:29:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:29:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:51 INFO - Fetch https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:29:52 INFO - Expected file size: 21936275 12:29:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:29:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:29:57 INFO - Fetch https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:29:57 INFO - Expected file size: 14414048 12:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:58 INFO - trying https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:58 INFO - Downloading https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:29:59 INFO - Downloaded 59536657 bytes. 12:29:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:59 INFO - mkdir: /builds/slave/test/properties 12:29:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:29:59 INFO - Writing to file /builds/slave/test/properties/build_url 12:29:59 INFO - Contents: 12:29:59 INFO - build_url:https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:30:00 INFO - Running post-action listener: _resource_record_post_action 12:30:00 INFO - Running post-action listener: set_extra_try_arguments 12:30:00 INFO - [mozharness: 2016-09-17 19:30:00.043710Z] Finished download-and-extract step (success) 12:30:00 INFO - [mozharness: 2016-09-17 19:30:00.043938Z] Running populate-webroot step. 12:30:00 INFO - Running pre-action listener: _resource_record_pre_action 12:30:00 INFO - Running main action method: populate_webroot 12:30:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:30:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:30:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:30:00 INFO - '--tppagecycles', 12:30:00 INFO - '7'], 12:30:00 INFO - 'tests': ['tsvgm']}}, 12:30:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:30:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:30:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:30:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:30:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['damp', 'tps']}, 12:30:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'tests': ['damp', 'tps']}, 12:30:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['dromaeo_dom']}, 12:30:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:30:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['basic_compositor_video']}, 12:30:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:30:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'other-e10s': {'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['a11yr', 12:30:00 INFO - 'ts_paint', 12:30:00 INFO - 'tpaint', 12:30:00 INFO - 'sessionrestore', 12:30:00 INFO - 'sessionrestore_no_auto_restore', 12:30:00 INFO - 'tabpaint']}, 12:30:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['tsvgx', 12:30:00 INFO - 'tsvgr_opacity', 12:30:00 INFO - 'tart', 12:30:00 INFO - 'tscrollx', 12:30:00 INFO - 'cart']}, 12:30:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:30:00 INFO - 'tsvgr_opacity', 12:30:00 INFO - 'tart', 12:30:00 INFO - 'tscrollx', 12:30:00 INFO - 'cart']}, 12:30:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'talos_options': ['--disable-e10s'], 12:30:00 INFO - 'tests': ['tp5o']}, 12:30:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'tests': ['tp5o']}, 12:30:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'talos_options': ['--disable-e10s', 12:30:00 INFO - '--xperf_path', 12:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:00 INFO - 'tests': ['tp5n']}, 12:30:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:30:00 INFO - 'talos_options': ['--xperf_path', 12:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:00 INFO - 'tests': ['tp5n']}}, 12:30:00 INFO - 'talos.zip': {'path': '', 12:30:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:30:00 INFO - Running post-action listener: _resource_record_post_action 12:30:00 INFO - [mozharness: 2016-09-17 19:30:00.064333Z] Finished populate-webroot step (success) 12:30:00 INFO - [mozharness: 2016-09-17 19:30:00.064422Z] Running create-virtualenv step. 12:30:00 INFO - Running pre-action listener: _resource_record_pre_action 12:30:00 INFO - Running main action method: create_virtualenv 12:30:00 INFO - Creating virtualenv /builds/slave/test/build/venv 12:30:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:30:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:30:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:30:00 INFO - Using real prefix '/usr' 12:30:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:30:00 INFO - Installing distribute.............................................................................................................................................................................................done. 12:30:02 INFO - Installing pip.................done. 12:30:02 INFO - Return code: 0 12:30:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:02 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:30:02 INFO - Using env: {'DISPLAY': ':0', 12:30:02 INFO - 'HOME': '/home/cltbld', 12:30:02 INFO - 'LANG': 'en_US.UTF-8', 12:30:02 INFO - 'LANGUAGE': 'en_US:en', 12:30:02 INFO - 'LOGNAME': 'cltbld', 12:30:02 INFO - 'MAIL': '/var/mail/cltbld', 12:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:02 INFO - 'MOZ_NO_REMOTE': '1', 12:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:02 INFO - 'NO_EM_RESTART': '1', 12:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:02 INFO - 'PWD': '/builds/slave/test', 12:30:02 INFO - 'SHELL': '/bin/bash', 12:30:02 INFO - 'SHLVL': '1', 12:30:02 INFO - 'TERM': 'linux', 12:30:02 INFO - 'TMOUT': '86400', 12:30:02 INFO - 'USER': 'cltbld', 12:30:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:02 INFO - '_': '/tools/buildbot/bin/python'} 12:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:30:02 INFO - Cleaning up... 12:30:02 INFO - Return code: 0 12:30:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:02 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:30:02 INFO - Using env: {'DISPLAY': ':0', 12:30:02 INFO - 'HOME': '/home/cltbld', 12:30:02 INFO - 'LANG': 'en_US.UTF-8', 12:30:02 INFO - 'LANGUAGE': 'en_US:en', 12:30:02 INFO - 'LOGNAME': 'cltbld', 12:30:02 INFO - 'MAIL': '/var/mail/cltbld', 12:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:02 INFO - 'MOZ_NO_REMOTE': '1', 12:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:02 INFO - 'NO_EM_RESTART': '1', 12:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:02 INFO - 'PWD': '/builds/slave/test', 12:30:02 INFO - 'SHELL': '/bin/bash', 12:30:02 INFO - 'SHLVL': '1', 12:30:02 INFO - 'TERM': 'linux', 12:30:02 INFO - 'TMOUT': '86400', 12:30:02 INFO - 'USER': 'cltbld', 12:30:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:02 INFO - '_': '/tools/buildbot/bin/python'} 12:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:03 INFO - Downloading/unpacking psutil>=3.1.1 12:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:05 INFO - Installing collected packages: psutil 12:30:05 INFO - Running setup.py install for psutil 12:30:05 INFO - building 'psutil._psutil_linux' extension 12:30:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:30:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:30:05 INFO - building 'psutil._psutil_posix' extension 12:30:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:30:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:05 INFO - Successfully installed psutil 12:30:05 INFO - Cleaning up... 12:30:05 INFO - Return code: 0 12:30:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:05 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:30:05 INFO - Using env: {'DISPLAY': ':0', 12:30:05 INFO - 'HOME': '/home/cltbld', 12:30:05 INFO - 'LANG': 'en_US.UTF-8', 12:30:05 INFO - 'LANGUAGE': 'en_US:en', 12:30:05 INFO - 'LOGNAME': 'cltbld', 12:30:05 INFO - 'MAIL': '/var/mail/cltbld', 12:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:05 INFO - 'MOZ_NO_REMOTE': '1', 12:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:05 INFO - 'NO_EM_RESTART': '1', 12:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:05 INFO - 'PWD': '/builds/slave/test', 12:30:05 INFO - 'SHELL': '/bin/bash', 12:30:05 INFO - 'SHLVL': '1', 12:30:05 INFO - 'TERM': 'linux', 12:30:05 INFO - 'TMOUT': '86400', 12:30:05 INFO - 'USER': 'cltbld', 12:30:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:05 INFO - '_': '/tools/buildbot/bin/python'} 12:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:30:07 INFO - Installing collected packages: mozsystemmonitor 12:30:07 INFO - Running setup.py install for mozsystemmonitor 12:30:07 INFO - Successfully installed mozsystemmonitor 12:30:07 INFO - Cleaning up... 12:30:07 INFO - Return code: 0 12:30:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:30: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')]} 12:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30: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')]} 12:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:07 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:30:07 INFO - Using env: {'DISPLAY': ':0', 12:30:07 INFO - 'HOME': '/home/cltbld', 12:30:07 INFO - 'LANG': 'en_US.UTF-8', 12:30:07 INFO - 'LANGUAGE': 'en_US:en', 12:30:07 INFO - 'LOGNAME': 'cltbld', 12:30:07 INFO - 'MAIL': '/var/mail/cltbld', 12:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:07 INFO - 'MOZ_NO_REMOTE': '1', 12:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:07 INFO - 'NO_EM_RESTART': '1', 12:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:07 INFO - 'PWD': '/builds/slave/test', 12:30:07 INFO - 'SHELL': '/bin/bash', 12:30:07 INFO - 'SHLVL': '1', 12:30:07 INFO - 'TERM': 'linux', 12:30:07 INFO - 'TMOUT': '86400', 12:30:07 INFO - 'USER': 'cltbld', 12:30:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:07 INFO - '_': '/tools/buildbot/bin/python'} 12:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:08 INFO - Downloading/unpacking jsonschema==2.5.1 12:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:30:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:30:10 INFO - warning: no files found matching '*.txt' 12:30:10 INFO - no previously-included directories found matching 'build' 12:30:10 INFO - no previously-included directories found matching 'dist' 12:30:10 INFO - no previously-included directories found matching '.git*' 12:30:10 INFO - Installing collected packages: jsonschema, functools32 12:30:10 INFO - Running setup.py install for functools32 12:30:10 INFO - warning: no files found matching '*.txt' 12:30:10 INFO - no previously-included directories found matching 'build' 12:30:10 INFO - no previously-included directories found matching 'dist' 12:30:10 INFO - no previously-included directories found matching '.git*' 12:30:10 INFO - Successfully installed jsonschema functools32 12:30:10 INFO - Cleaning up... 12:30:10 INFO - Return code: 0 12:30:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:10 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:30:10 INFO - Using env: {'DISPLAY': ':0', 12:30:10 INFO - 'HOME': '/home/cltbld', 12:30:10 INFO - 'LANG': 'en_US.UTF-8', 12:30:10 INFO - 'LANGUAGE': 'en_US:en', 12:30:10 INFO - 'LOGNAME': 'cltbld', 12:30:10 INFO - 'MAIL': '/var/mail/cltbld', 12:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:10 INFO - 'MOZ_NO_REMOTE': '1', 12:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:10 INFO - 'NO_EM_RESTART': '1', 12:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:10 INFO - 'PWD': '/builds/slave/test', 12:30:10 INFO - 'SHELL': '/bin/bash', 12:30:10 INFO - 'SHLVL': '1', 12:30:10 INFO - 'TERM': 'linux', 12:30:10 INFO - 'TMOUT': '86400', 12:30:10 INFO - 'USER': 'cltbld', 12:30:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:10 INFO - '_': '/tools/buildbot/bin/python'} 12:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:30:10 INFO - Cleaning up... 12:30:10 INFO - Return code: 0 12:30:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:10 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:30:10 INFO - Using env: {'DISPLAY': ':0', 12:30:10 INFO - 'HOME': '/home/cltbld', 12:30:10 INFO - 'LANG': 'en_US.UTF-8', 12:30:10 INFO - 'LANGUAGE': 'en_US:en', 12:30:10 INFO - 'LOGNAME': 'cltbld', 12:30:10 INFO - 'MAIL': '/var/mail/cltbld', 12:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:10 INFO - 'MOZ_NO_REMOTE': '1', 12:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:10 INFO - 'NO_EM_RESTART': '1', 12:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:10 INFO - 'PWD': '/builds/slave/test', 12:30:10 INFO - 'SHELL': '/bin/bash', 12:30:10 INFO - 'SHLVL': '1', 12:30:10 INFO - 'TERM': 'linux', 12:30:10 INFO - 'TMOUT': '86400', 12:30:10 INFO - 'USER': 'cltbld', 12:30:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:10 INFO - '_': '/tools/buildbot/bin/python'} 12:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:12 INFO - Downloading blobuploader-1.2.4.tar.gz 12:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:30:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:30:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:13 INFO - Downloading docopt-0.6.1.tar.gz 12:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:30:13 INFO - Installing collected packages: blobuploader, requests, docopt 12:30:13 INFO - Running setup.py install for blobuploader 12:30:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:30:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:30:13 INFO - Running setup.py install for requests 12:30:14 INFO - Running setup.py install for docopt 12:30:14 INFO - Successfully installed blobuploader requests docopt 12:30:14 INFO - Cleaning up... 12:30:14 INFO - Return code: 0 12:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:14 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:30:14 INFO - Using env: {'DISPLAY': ':0', 12:30:14 INFO - 'HOME': '/home/cltbld', 12:30:14 INFO - 'LANG': 'en_US.UTF-8', 12:30:14 INFO - 'LANGUAGE': 'en_US:en', 12:30:14 INFO - 'LOGNAME': 'cltbld', 12:30:14 INFO - 'MAIL': '/var/mail/cltbld', 12:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:14 INFO - 'MOZ_NO_REMOTE': '1', 12:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:14 INFO - 'NO_EM_RESTART': '1', 12:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:14 INFO - 'PWD': '/builds/slave/test', 12:30:14 INFO - 'SHELL': '/bin/bash', 12:30:14 INFO - 'SHLVL': '1', 12:30:14 INFO - 'TERM': 'linux', 12:30:14 INFO - 'TMOUT': '86400', 12:30:14 INFO - 'USER': 'cltbld', 12:30:14 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:14 INFO - '_': '/tools/buildbot/bin/python'} 12:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:14 INFO - Running setup.py (path:/tmp/pip-yXxL6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:14 INFO - Running setup.py (path:/tmp/pip-L6G_HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:14 INFO - Running setup.py (path:/tmp/pip-pJG1nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:14 INFO - Running setup.py (path:/tmp/pip-6ChpxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:14 INFO - Running setup.py (path:/tmp/pip-HYrn4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:14 INFO - Running setup.py (path:/tmp/pip-1f1Jvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:14 INFO - Running setup.py (path:/tmp/pip-uQD7H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:14 INFO - Running setup.py (path:/tmp/pip-D1WL76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:15 INFO - Running setup.py (path:/tmp/pip-lm7jHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:15 INFO - Running setup.py (path:/tmp/pip-gfPN_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:15 INFO - Running setup.py (path:/tmp/pip-aI2T1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:15 INFO - Running setup.py (path:/tmp/pip-cgDT9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:15 INFO - Running setup.py (path:/tmp/pip-QbTLrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:15 INFO - Running setup.py (path:/tmp/pip-YKi5T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:15 INFO - Running setup.py (path:/tmp/pip-bIgM94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:15 INFO - Running setup.py (path:/tmp/pip-MV8Pa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:15 INFO - Running setup.py (path:/tmp/pip-W8E8Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:30:15 INFO - Running setup.py install for manifestparser 12:30:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:30:15 INFO - Running setup.py install for mozcrash 12:30:15 INFO - Running setup.py install for mozdebug 12:30:15 INFO - Running setup.py install for mozdevice 12:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for mozfile 12:30:16 INFO - Running setup.py install for mozhttpd 12:30:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for mozinfo 12:30:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for mozInstall 12:30:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for mozleak 12:30:16 INFO - Running setup.py install for mozlog 12:30:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for moznetwork 12:30:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:30:16 INFO - Running setup.py install for mozprocess 12:30:16 INFO - Running setup.py install for mozprofile 12:30:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:30:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:30:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:30:17 INFO - Running setup.py install for mozrunner 12:30:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:30:17 INFO - Running setup.py install for mozscreenshot 12:30:17 INFO - Running setup.py install for moztest 12:30:17 INFO - Running setup.py install for mozversion 12:30:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:30:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:30:17 INFO - Cleaning up... 12:30:17 INFO - Return code: 0 12:30:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:17 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:30:17 INFO - Using env: {'DISPLAY': ':0', 12:30:17 INFO - 'HOME': '/home/cltbld', 12:30:17 INFO - 'LANG': 'en_US.UTF-8', 12:30:17 INFO - 'LANGUAGE': 'en_US:en', 12:30:17 INFO - 'LOGNAME': 'cltbld', 12:30:17 INFO - 'MAIL': '/var/mail/cltbld', 12:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:17 INFO - 'MOZ_NO_REMOTE': '1', 12:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:17 INFO - 'NO_EM_RESTART': '1', 12:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:17 INFO - 'PWD': '/builds/slave/test', 12:30:17 INFO - 'SHELL': '/bin/bash', 12:30:17 INFO - 'SHLVL': '1', 12:30:17 INFO - 'TERM': 'linux', 12:30:17 INFO - 'TMOUT': '86400', 12:30:17 INFO - 'USER': 'cltbld', 12:30:17 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:17 INFO - '_': '/tools/buildbot/bin/python'} 12:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:17 INFO - Running setup.py (path:/tmp/pip-M99WIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:17 INFO - Running setup.py (path:/tmp/pip-f88tVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:17 INFO - Running setup.py (path:/tmp/pip-1Rhc8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:17 INFO - Running setup.py (path:/tmp/pip-SwLCU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:18 INFO - Running setup.py (path:/tmp/pip-5nSgEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:18 INFO - Running setup.py (path:/tmp/pip-T17iJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:18 INFO - Running setup.py (path:/tmp/pip-bPV97G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:18 INFO - Running setup.py (path:/tmp/pip-r9OT4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:18 INFO - Running setup.py (path:/tmp/pip-3G3sLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:18 INFO - Running setup.py (path:/tmp/pip-rTmHbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:18 INFO - Running setup.py (path:/tmp/pip-EbHdvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:18 INFO - Running setup.py (path:/tmp/pip-15gZ5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:18 INFO - Running setup.py (path:/tmp/pip-1INgd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:18 INFO - Running setup.py (path:/tmp/pip-d_wGP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:18 INFO - Running setup.py (path:/tmp/pip-MuL6ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:18 INFO - Running setup.py (path:/tmp/pip-bYAKO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:18 INFO - Running setup.py (path:/tmp/pip-wVVNdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:30:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:20 INFO - Downloading blessings-1.6.tar.gz 12:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:30:20 INFO - Installing collected packages: blessings 12:30:20 INFO - Running setup.py install for blessings 12:30:21 INFO - Successfully installed blessings 12:30:21 INFO - Cleaning up... 12:30:21 INFO - Return code: 0 12:30:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:21 INFO - Reading from file tmpfile_stdout 12:30:21 INFO - Current package versions: 12:30:21 INFO - argparse == 1.2.1 12:30:21 INFO - blessings == 1.6 12:30:21 INFO - blobuploader == 1.2.4 12:30:21 INFO - docopt == 0.6.1 12:30:21 INFO - functools32 == 3.2.3-2 12:30:21 INFO - jsonschema == 2.5.1 12:30:21 INFO - manifestparser == 1.1 12:30:21 INFO - mozInstall == 1.12 12:30:21 INFO - mozcrash == 0.17 12:30:21 INFO - mozdebug == 0.1 12:30:21 INFO - mozdevice == 0.48 12:30:21 INFO - mozfile == 1.2 12:30:21 INFO - mozhttpd == 0.7 12:30:21 INFO - mozinfo == 0.9 12:30:21 INFO - mozleak == 0.1 12:30:21 INFO - mozlog == 3.4 12:30:21 INFO - moznetwork == 0.27 12:30:21 INFO - mozprocess == 0.23 12:30:21 INFO - mozprofile == 0.28 12:30:21 INFO - mozrunner == 6.12 12:30:21 INFO - mozscreenshot == 0.1 12:30:21 INFO - mozsystemmonitor == 0.3 12:30:21 INFO - moztest == 0.7 12:30:21 INFO - mozversion == 1.4 12:30:21 INFO - psutil == 3.1.1 12:30:21 INFO - requests == 1.2.3 12:30:21 INFO - wsgiref == 0.1.2 12:30:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:21 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:30:21 INFO - Using env: {'DISPLAY': ':0', 12:30:21 INFO - 'HOME': '/home/cltbld', 12:30:21 INFO - 'LANG': 'en_US.UTF-8', 12:30:21 INFO - 'LANGUAGE': 'en_US:en', 12:30:21 INFO - 'LOGNAME': 'cltbld', 12:30:21 INFO - 'MAIL': '/var/mail/cltbld', 12:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:21 INFO - 'MOZ_NO_REMOTE': '1', 12:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:21 INFO - 'NO_EM_RESTART': '1', 12:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:21 INFO - 'PWD': '/builds/slave/test', 12:30:21 INFO - 'SHELL': '/bin/bash', 12:30:21 INFO - 'SHLVL': '1', 12:30:21 INFO - 'TERM': 'linux', 12:30:21 INFO - 'TMOUT': '86400', 12:30:21 INFO - 'USER': 'cltbld', 12:30:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:21 INFO - '_': '/tools/buildbot/bin/python'} 12:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:30:21 INFO - Cleaning up... 12:30:21 INFO - Return code: 0 12:30:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:21 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 0x7f5caee33f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2552790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:30:21 INFO - Using env: {'DISPLAY': ':0', 12:30:21 INFO - 'HOME': '/home/cltbld', 12:30:21 INFO - 'LANG': 'en_US.UTF-8', 12:30:21 INFO - 'LANGUAGE': 'en_US:en', 12:30:21 INFO - 'LOGNAME': 'cltbld', 12:30:21 INFO - 'MAIL': '/var/mail/cltbld', 12:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:21 INFO - 'MOZ_NO_REMOTE': '1', 12:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:21 INFO - 'NO_EM_RESTART': '1', 12:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:21 INFO - 'PWD': '/builds/slave/test', 12:30:21 INFO - 'SHELL': '/bin/bash', 12:30:21 INFO - 'SHLVL': '1', 12:30:21 INFO - 'TERM': 'linux', 12:30:21 INFO - 'TMOUT': '86400', 12:30:21 INFO - 'USER': 'cltbld', 12:30:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:21 INFO - '_': '/tools/buildbot/bin/python'} 12:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:30:21 INFO - Cleaning up... 12:30:21 INFO - Return code: 0 12:30:21 INFO - Running post-action listener: _resource_record_post_action 12:30:21 INFO - Running post-action listener: _start_resource_monitoring 12:30:21 INFO - Starting resource monitoring. 12:30:21 INFO - [mozharness: 2016-09-17 19:30:21.780064Z] Finished create-virtualenv step (success) 12:30:21 INFO - [mozharness: 2016-09-17 19:30:21.780572Z] Running install step. 12:30:21 INFO - Running pre-action listener: _resource_record_pre_action 12:30:21 INFO - Running main action method: install 12:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:21 INFO - Reading from file tmpfile_stdout 12:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:30:22 INFO - Reading from file tmpfile_stdout 12:30:22 INFO - Output received: 12:30:22 INFO - Usage: mozinstall [options] installer 12:30:22 INFO - Options: 12:30:22 INFO - -h, --help show this help message and exit 12:30:22 INFO - -d DEST, --destination=DEST 12:30:22 INFO - Directory to install application into. [default: 12:30:22 INFO - "/builds/slave/test"] 12:30:22 INFO - --app=APP Application being installed. [default: firefox] 12:30:22 INFO - mkdir: /builds/slave/test/build/application 12:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:30:39 INFO - Reading from file tmpfile_stdout 12:30:39 INFO - Output received: 12:30:39 INFO - /builds/slave/test/build/application/firefox/firefox 12:30:39 INFO - Running post-action listener: _resource_record_post_action 12:30:39 INFO - [mozharness: 2016-09-17 19:30:39.012142Z] Finished install step (success) 12:30:39 INFO - [mozharness: 2016-09-17 19:30:39.012387Z] Running run-tests step. 12:30:39 INFO - Running pre-action listener: _resource_record_pre_action 12:30:39 INFO - Running main action method: run_tests 12:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:39 INFO - Python 2.7.3 12:30:39 INFO - Return code: 0 12:30:39 INFO - grabbing minidump binary from tooltool 12:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:39 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 0x254f930>, '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 0x2550830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2550cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:30:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:30:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:30:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:39 INFO - Return code: 0 12:30:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:30:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:30:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:30:39 INFO - Using env: {'DISPLAY': ':0', 12:30:39 INFO - 'HOME': '/home/cltbld', 12:30:39 INFO - 'LANG': 'en_US.UTF-8', 12:30:39 INFO - 'LANGUAGE': 'en_US:en', 12:30:39 INFO - 'LOGNAME': 'cltbld', 12:30:39 INFO - 'MAIL': '/var/mail/cltbld', 12:30:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:39 INFO - 'MOZ_NO_REMOTE': '1', 12:30:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:39 INFO - 'NO_EM_RESTART': '1', 12:30:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:39 INFO - 'PWD': '/builds/slave/test', 12:30:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:39 INFO - 'SHELL': '/bin/bash', 12:30:39 INFO - 'SHLVL': '1', 12:30:39 INFO - 'TERM': 'linux', 12:30:39 INFO - 'TMOUT': '86400', 12:30:39 INFO - 'USER': 'cltbld', 12:30:39 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378', 12:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:39 INFO - '_': '/tools/buildbot/bin/python'} 12:30:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:30:39 INFO - mozversion application_buildid: 20160917093048 12:30:39 INFO - mozversion application_changeset: ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 12:30:39 INFO - mozversion application_display_name: Nightly 12:30:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:39 INFO - mozversion application_name: Firefox 12:30:39 INFO - mozversion application_remotingname: firefox 12:30:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:30:39 INFO - mozversion application_vendor: Mozilla 12:30:39 INFO - mozversion application_version: 51.0a1 12:30:39 INFO - mozversion platform_buildid: 20160917093048 12:30:39 INFO - mozversion platform_changeset: ef3c1ce0c1fb504f971bc5da9a2a74374d5aebd0 12:30:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:30:39 INFO - mozversion platform_version: 51.0a1 12:30:39 INFO - using testdate: 1474140639 12:30:39 INFO - actual date: 1474140639 12:30:39 INFO - starting webserver on 'localhost:51063' 12:30:39 INFO - SUITE-START | Running 2 tests 12:30:39 INFO - TEST-START | dromaeo_css 12:30:39 INFO - operating with platform_type : linux_ 12:30:39 INFO - Initialising browser for dromaeo_css test... 12:30:39 INFO - TEST-INFO | started process 31735 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprJU6qx/profile http://localhost:51063/getInfo.html) 12:30:43 INFO - PROCESS | 31735 | __metrics Screen width/height:1600/1200 12:30:43 INFO - PROCESS | 31735 | colorDepth:24 12:30:43 INFO - PROCESS | 31735 | Browser inner width/height: 1024/697 12:30:43 INFO - PROCESS | 31735 | __metrics 12:30:44 INFO - PROCESS | 31735 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:30:44 INFO - PROCESS | 31735 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:44 INFO - PROCESS | 31735 | console.error: PushService: 12:30:44 INFO - PROCESS | 31735 | stateChangeProcessEnqueue: Error transitioning state 12:30:44 INFO - PROCESS | 31735 | UnknownError 12:30:45 INFO - TEST-INFO | 31735: exit 0 12:30:45 INFO - Browser initialized. 12:30:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:45 INFO - TEST-INFO | started process 31819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprJU6qx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:57 INFO - PROCESS | 31819 | RSS: Main: 173453312 12:30:57 INFO - PROCESS | 31819 | 12:31:06 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:31:07 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 2.01673 seconds. 12:31:39 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:31:42 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:31:49 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 12:31:49 INFO - PROCESS | 31819 | 1474140709233 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:31:57 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:32:11 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 12:32:13 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:32:15 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 12:32:17 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:32:19 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 12:32:24 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 12:32:33 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:32:36 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 12:32:41 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 12:32:45 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.13338 seconds. 12:32:56 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:33:00 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.10005 seconds. 12:33:09 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:33:12 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 12:33:12 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 12:33:13 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.16668 seconds. 12:33:18 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 12:33:22 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:33:34 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 12:33:41 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 12:33:44 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:33:51 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:33:53 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:33:57 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:33:58 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51063/tests/dromaeo/cssquery-ext.html) 12:33:59 INFO - PROCESS | 31819 | RSS: Main: 318144512 12:33:59 INFO - PROCESS | 31819 | 12:34:03 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 12:34:10 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:46 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:50 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:35:00 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:35:01 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:35:07 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:35:15 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:35:20 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:35:21 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:35:45 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 12:35:47 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:35:49 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:35:51 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:36:22 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 12:36:24 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:36:28 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:36:40 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:36:50 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:36:54 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:37:02 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-ext.html (next: http://localhost:51063/tests/dromaeo/cssquery-jquery.html) 12:37:03 INFO - PROCESS | 31819 | RSS: Main: 276406272 12:37:03 INFO - PROCESS | 31819 | 12:37:06 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 12:37:17 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:37:19 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:37:24 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:37:41 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:37:49 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:46 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:38:50 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:53 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:39:00 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:08 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:10 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:39:22 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:30 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:43 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 12:39:52 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:40:00 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 12:40:05 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51063/tests/dromaeo/cssquery-mootools.html) 12:40:05 INFO - PROCESS | 31819 | RSS: Main: 255578112 12:40:05 INFO - PROCESS | 31819 | 12:40:10 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:40:11 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 2.03339 seconds. 12:40:18 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:40:26 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:40:28 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:40:32 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 12:40:43 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:40:46 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:41:02 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:41:14 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:41:26 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:41:36 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:41:44 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 12:42:17 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:42:25 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:42:44 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:42:45 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:42:55 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:43:07 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51063/tests/dromaeo/cssquery-prototype.html) 12:43:08 INFO - PROCESS | 31819 | RSS: Main: 236298240 12:43:08 INFO - PROCESS | 31819 | 12:43:34 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:43:43 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:43:45 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:43:55 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:44:01 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:44:26 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:44:35 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:44:42 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 12:44:52 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:44:58 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:45:02 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:45:14 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:45:15 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 2.00009 seconds. 12:45:16 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 12:45:18 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:45:28 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:45:30 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:45:34 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:45:45 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 12:45:56 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:45:58 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:46:04 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:46:10 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51063/tests/dromaeo/cssquery-yui.html) 12:46:11 INFO - PROCESS | 31819 | RSS: Main: 217018368 12:46:11 INFO - PROCESS | 31819 | 12:46:14 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:46:26 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:46:29 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:46:38 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:46:54 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:47:04 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:47:16 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:47:35 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:47:39 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:48:17 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:48:35 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:48:50 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 12:49:04 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:49:09 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:49:11 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:49:12 INFO - PROCESS | 31819 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:49:14 INFO - PROCESS | 31819 | Cycle 1(1): loaded http://localhost:51063/tests/dromaeo/cssquery-yui.html (next: http://localhost:51063/tests/dromaeo/cssquery-dojo.html) 12:49:14 INFO - PROCESS | 31819 | RSS: Main: 307834880 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | __start_tp_report 12:49:14 INFO - PROCESS | 31819 | _x_x_mozilla_page_load 12:49:14 INFO - PROCESS | 31819 | _x_x_mozilla_page_load_details 12:49:14 INFO - PROCESS | 31819 | |i|pagename|runs| 12:49:14 INFO - PROCESS | 31819 | |0;dojo.html;2972.2123637236127;2997.925051873703;3006.068118883146;3016.86424110915;3054.5418187271907;5082.288479612848;5165.690058596487;5173.9482605173935;5227.3465816772905;5258.684478931262;5140.820071297505;5200.167973124301;5248.239005344232;5256.316678831747;5290.232916227144;4712.528747125277;4754.8098076077085;4790.496997815214;4793.9280910786365;4863.951360486403;4869.050535145648;4900.289458028584;4903.387076615423;4916.926246106311;4943.876403089916;4696.154692155411;4726.385569875894;4731.124741922756;4764.404449443819;4822.97588512057;4714.976425117887;4728.078024785168;4776.379070720819;4802.207635740098;4805.327254184417;4674.906501869978;4692.249240121599;4724.126036683225;4728.40894888139;4772.427308722976;5157.252198431244;5194.870128246786;5202.635815492917;5208.869778255536;5247.816326428595;5477.260569823103;5552.666839989573;5559.416261292547;5566.721663916788;5641.943580564182;5619.6066275360745;5623.943760562423;5632.831015069513;5639.8590035249035;5645.294338207724;5110.66780659256;5215.582753379758;5217.895642087175;5218.608604354688;5282.894342113174;5549.084401073858;5558.805441809517;5579.163125531203;5634.1830434586755;5654.491095801398;5340.332458442695;5361.383440904346;5364;5372.543333816591;5378.784848606012;5391.892162156735;5413.810516631899;5456.263404440429;5464.726763661801;5529.115341545334;5425.864353391118;5470.507654311131;5473.042217611902;5563.221149039137;5578.63738856973;5594.636348637326;5599.048161812501;5603.579731520153;5607.299087614048;5657.4059723728415;4341.435613370241;4344.261475549164;4348.7390756554705;4384.846530371422;4394.2749446340995;3831.04223944014;3840.2319536092336;3864.0146762575923;3875.593062728373;3902.6097390260747;5293.9205911910585;5295.232191332235;5305.920411193835;5348.090824559834;5380.811671591476;5622.57830662702;5639.915401268984;5673.602847800615;5676.631018983753;5677.716114194273;5158.92261616076;5160.174372100446;5204.0372531081875;5354.518093371616;5421.891562168735;4860.1251774680895;4910.950890491049;4916.70499770015;4966.478519755446;4971.304017437562;5825.359210486843;5829.271341082364;5830.854228644318;5835.5915085944425;5886.205362276123;5631.1271752878365;5634.549236061023;5640.069388551007;5645.350784659817;5653.293338332708;5644.238027866186;5702.059160238514;5733.340665823317;5784.595078344475;5816.185733997159;5461.781528738806;5519.144532597454;5523.585731070106;5582.358028826737;5584.106542953108;5561.6663000220115;5607.663540187602;5616.297962754656;5623.662580245198;5636.605437619327;5270.4202537721585;5316.3354580677415;5423.810166644149;5494.80768173128;5561.694106824163;5420.430854760193;5484.177373394023;5485.259489968803;5538.307711536058;5549.500544950974;5419.972900135474;5429.7556609953435;5459.590530710292;5462.9180562292395;5465.726713664221;5353.0632139374675;5426.511613954763;5481.917771233355;5513.062779327443;5579.386267510652;5297.417284098669;5384.326959130109;5406.945930540644;5413.729313534386;5483.972580137074 12:49:14 INFO - PROCESS | 31819 | |1;ext.html;543.52068696331;598.4314900844198;610.7526451787026;610.9144719739237;632.8639342541353;32691.836540817294;41783.79108104459;42571;42575.361369579405;42873.35689964653;45271;45729.7713511432;45762;45778.31332530015;45784.77107614466;8938.37431379802;9076.954615226914;9087.681931132413;9089.909100908972;9097.954510227473;8847.601857916408;8895;8967.730968070968;8968.282537396992;8978.86531702025;8959.64161433545;8990.910090899106;9009.594568244445;9019.774505637346;9023.45859248444;8949.507777072258;8961.372703910729;8970.237529809974;8978.147076027766;9013.00856905743;8838.911610883873;8916.821663566692;8918.509481978488;8921.241694455914;8921.375503714742;42019.57980420218;42315.78842105801;42334.78832605848;42423;42446.787766060974;3488.9127771805656;3494.702950249232;3526.118470382404;3775.2260786538695;4024.376221685614;31729.8413507931;31954.360912781845;31954.84022579872;31994.6800531994;32211.35577288441;3996.3805610130185;4084.8774536763945;4125.793710314502;4154.252234597771;4342.522322544518;350534;354777;355341.25222468877;356452;357960.9999999974;58909.11636325459;59108.70445647744;59114.70442647759;59158.40841591615;59463;53846.46153538493;55169;62177.067343989875;62233.37766622366;62294;15770.526884193492;16450.424235151953;16525.173741312887;16762.83237167637;16765.24556394965;12453.1905426147;12459.75080498403;12475.625731228078;12478.18891772032;12479.500819967281;16585.585360365847;18909.24363025491;18949.81050189508;18955.81044189568;19048.80951190498;6202.503799696104;7188.964055179691;7244.877044058179;7261.237570055174;7317.890231646529;932.4265576670434;1002.1081237698454;1017.9616790873267;1064.6060957445795;1088.374184843718;32235.194120147185;32391.190220244684;32480.35039299248;32550.83724581362;33028.3394332112;1066.594694016269;1081.6376513867942;1103.27184058521;1106.5407855739938;1146.2893006336126;16087.919560402122;16799.16004199785;16845;16964.575885602957;17001.659966800595;584515;589371;591466;593623;595910;57474.7126264366;57898;57925.71037144872;58016;58083;1279.8080287956882;1313.8554758976504;1336.8462626797848;1346.9528566500321;1347.3330701302918;29577.112686619435;30821.691783081882;31317.8434107828;31406.842965785025;32030.839845800623;50911.74544127404;51170.232446512586;51294.74352628362;51330;51331.48668513267;470688;471549;472195;472566;472659;24825.87587062053;25328.11351602685;26332;26990.73009269882;27766;46031;46205.537944620126;46468.53531464777;46646;47689.28466073081;38761;39301;40154.1969160621;40617;41521;39061.41407878941;39693.404598931615;40225.798871005456;40233.396499053124;41397.37903931383;36322;36749.81625091857;36973.81513092417;37497.62502374942;37724.81137594295;33943.66056339405;35298.47052294269;35568.77249100381;36283.27433451369;36429.45355819612;26547.469050619267;27107.457850843268;27471.901123954816;27489.72510274872;27596.724032759415 12:49:14 INFO - PROCESS | 31819 | |2;jquery.html;372.116223968076;387.56592616269774;388.53764020815225;399.6423201234893;407.5150570820723;17655.646887062267;19619.901900490495;19659.21363145476;19694.113764880596;19740;18433.631327373445;18636.068196590186;19133.61732765344;19155.80844191558;19308.61382772344;5384.973075134639;5436.157395603688;5553.611247212697;5571.13647384656;5665.575081868856;5518.475744804237;5523.226748255227;5556.749946252408;5562.16567514874;5564.4713752193475;5522.392536820947;5532.114861622142;5533.474319939599;5537.446255374471;5765.308163020422;5849.093390524455;5884;5885.529157667376;5887.1463637772495;5890.617109887844;5548.167774833767;5694.6867922264255;5719.25649665545;5751.482366587027;5759.856003599902;13910.234937078558;14048.367823447968;14066.718665626733;14116.223607701675;14200.573982780534;2217.3569664797187;2302.804261637763;2436.378723425533;2700.2313484667575;2865.9957389269657;9765.804683906354;9891.159251463634;10373.377597344184;10498.632547860789;11437.027852632536;1897.9051047447572;1914.5117994911348;1934.4970307719952;1943.37811900192;1988.5724569217687;80264.19735802626;82131;82754;84081.57959210227;84202;10722;10838.403887786248;11034.172437067253;11124.382863513305;11160.386178760084;15645.139517326656;15655.452059177875;15672.686546269239;15676.372945082298;15699.843001569838;7898.170692077249;8117.959410202911;8133.430659853753;8331.416800823885;8345;7705.922940770521;7741.522712731984;7755.68977240904;7923.484973476706;7961.402894782915;4681.648876334289;4774.809007639725;4818.855434336975;4851.320815085891;4927.408710954708;1903.8096190381127;1939.3903060459784;1971.684530475117;1979.2281010405952;2028.3407892435018;833.3624777045565;924.7272054743732;932.6735642525061;982.40564458503;1048.7168464514539;18532.166052527667;19264.240703379797;19311;19433.611327773364;19438.22247110128;937.1331518345503;968.5254225429488;973.5083782689696;983.1201075037801;989.5151375825938;10025.147862431775;10288.742781430525;10754.40850753216;10809.405482698527;10812.56749730002;456502;458459;464483;464692;473068;85260.57369713111;88811;89885;91563.54218228866;93499.72750953684;990.3117333453181;1013.2400699475394;1053.5943661690305;1075.8799481503702;1081.1350919264557;16614;16871.047566675537;17256.913715431845;17335.393261235797;17435.738463923306;30688;39123.217535649695;40296.798516007235;40535.391969121076;42459.363109554;51732.224016639026;52520.47479525156;53645;54509.72745136249;54794;7687.692492300469;8415.8737618937;8444;8634.266087382643;8636.438631488933;12504.687382815138;13352.599422017354;13463;13474.797878032034;13555;10962.835557466804;11441;11686.94156529212;11916.880831191924;12191.329476878856;11367.602133925273;11431.657050288504;11475.655730328104;11619.825702614298;11690.70773230676;11180.608678696532;11898.821517677068;12055.336956467128;12080.297927269117;12237.816432753338;8662.393632445921;8687.609057592297;8704.173103555153;8739.12608739121;9076.228520575825;9133.588988495401;11168.29234236926;11414.657560273205;11447.542098316308;12056.336901470504 12:49:14 INFO - PROCESS | 31819 | |3;mootools.html;1299;1429.2210745143896;1443.8844892408608;1568.1767072287055;1714.7599336092958;7360.07999000125;7953.72161974331;8138.226868447503;8264.214899584545;8425.157484251571;8259.380546459024;8343.582820858963;8347.373946953972;8415.621297041633;8425.873611895826;2334.9182778602753;2348.58899692554;2362.338545207345;2369.490559529701;2370.78662920338;2299.2067736630834;2330.067972810872;2356.4108972756812;2363.0665886974634;2363.9763602364014;2323.1520495019367;2323.256557901472;2328.196772113618;2369.2536850892;2378.357843382286;2286.1084177170915;2307.157887371106;2316.4672125411166;2316.51353215824;2322.941926451835;2263.592553340398;2281.566502364562;2288.2448791898632;2319.6404557293477;2325.4070212095976;8066.274035336848;8109.4323397362205;8133.674653013873;8147.348212143014;8308.626111824982;4870.902581948341;4923.876903077452;5002.374703162105;5007.69953802773;5017.774200161001;3138.2938838761324;3308.470644696861;3364.2094107884627;3374.021533755208;3390.4066788312193;1160.3328086350298;1209.246359084514;1219.8170274458814;1223.1988047828597;1242.9254244745255;10810.67567972962;10907.127429805676;10908.836367454494;10923.39921304328;10935.945320273428;4137.586241375839;4296.505901821331;4375.453068366454;4378.934315985262;4407.559244075567;8850.822983540293;8859.64561417536;8865.113488651212;8866.822663546822;8867.246284065926;4940.876478088005;4945.282933974553;4950.084234416572;4951.282064100686;4955.182394904872;4025.295573274665;4136.834526618902;4434.490033646108;4523.638108951276;4653.627709783209;3547.8758243461357;3570.7500474966505;3675.9264814703556;3691.6123807000417;3745.6067112952746;1137.9601713939971;1154.7863645225493;1160.8200728887036;1171.478691982077;1182.3733421286731;832.9708460203985;937.6249500199838;968.070652173921;983.233078199006;997.3218211616025;5574.470425309589;5621.634593751393;5624.446193524154;5840.3575606683235;5984.940150598525;460.4198709625895;463.8979424526599;473.66606542387535;475.113912553086;476.25703901913124;3926.1166237596312;4039.091204478969;4057.736247143926;4080.1227736036303;4155.065110350147;9904.950475247577;10209.336393134423;10301.227407944434;10309.07218350352;10317.793644127225;10078.09297163244;10163.339382940085;10189.184865210766;10230.181585473145;10249.538770755335;7560.735374261874;7613.238676132453;7758.340541053961;7770.572618506037;7771.533707977539;5642.379338272704;5654;5805.8548536286935;5820.359760426434;5825.184474173704;9191.402558833654;9289.210417114351;9350.906490935004;9404.717858464257;9544.856827147738;9069.410488318239;9242.861357079515;9342.906570934203;9610.95194524023;9667.613295468384;6044.365341639064;6241.751649670175;6242.250929888442;6858.142732158481;6873.415759660485;7452.590107544137;7537.54774713519;7682.961585192038;7859.17478664732;7941.523508589503;7064;7098.148222213367;7140.571565706075;7144.2855714430225;7293.708251669874;7045.365917067488;7059.964700176466;7073.858522829411;7132.251113633201;7216.7113315466795;6117;6235.532335074979;6314.8737025258315;6406.071119687713;6633.038209459505;5195.350581177353;5212.166053431432;5236.502532259429;5337.626366154332;5343.812966546152;5007.148784706682;5097.643164978416;5108.821191258288;5146.974265128651;5236.528712415901 12:49:14 INFO - PROCESS | 31819 | |4;prototype.html;316.7165386978654;320.3992514036182;322.87246537617636;327.75090930892515;327.77219832216605;5070.771815268313;5248.317718696569;5275.920861187075;5298.682079075258;5312.867178320539;5071.771770270338;5153.2270159476;5160.225966105086;5166.793328266875;5188.455212202721;4109.958900410988;4115.464989551354;4122.649574786132;4123.484564429446;4163.605926592221;3998.880313512221;4109.198706252282;4154.258189594868;4159.958400416002;4173.227952828721;4060.8578699745517;4061.3501839705655;4083;4083.7549747015128;4092.4475195848477;4061.735987160825;4081.795910204493;4091.488563929509;4098.979505102485;4125.29869922111;3593.2274560969254;3606.855725770966;3607.3867442534834;3610.8194590270646;3617.9095522611883;5078.984203159383;5107.157319042353;5110.9744451277875;5116.283720279164;5117.974410127926;4607.654425918036;4611.492735799059;4623.468301145378;4625.791839367236;4630.699004564693;5405.945940540623;5426.810061647863;5435.728213589305;5444.346678398578;5454.263674403945;3731.626837316274;3885.5920128386397;3891.7664940103687;3901.8634347797974;3932.960670393288;5632.915506267409;5663.405342439067;5664.0937450007805;5671.602987790856;5679.290088738908;3978.3634618460906;4022.8390864365683;4031.7580945143386;4049.655779258737;4067.3695577185586;3999.9800000999808;4065.6747460203114;4079.9592004079577;4094.61101195386;4104.302268614343;4598.034412773288;4604.907901841944;4610.907781844411;4628.3983082199775;4629.981404091094;5469.206964990054;5471.534919531805;5483.150111732688;5488;5522.3649280333275;1285.7749893768553;1312.6193403912948;1364.1201425080767;1374.305975482375;1386.6602682342689;885.4465958775766;886.3751055505853;893.5978809535735;901.715959472764;911.8495448250965;4615.95384046162;4620;4668.486466488684;4681.859544213679;4708.340832283483;4819.879503012453;4896.5837903777865;4901.950980490221;4910.170067347474;4923.433805112386;4665.690483210832;4667.17979987805;4670.6497012724185;4694.990577025956;4695.906081878412;2736.480068786924;2944.2933695912825;2962.252271160026;2978.8978078110804;2983.4331477019296;5794;5797.333306669702;5809.448102430347;5809.970950145307;5840.211571437886;5458.45966344161;5618.803341883096;5657.472482429804;5672.744726487235;5702.087665973508;5730.169125476783;5732.7706891723865;5763.366029736726;5772.682502462405;5785.190073389729;5558.610897237155;5591;5606.439356064524;5618.033688209094;5632.831015069554;5326.174442961347;5515.766114723543;5516.538117398813;5523.558115350843;5543.72281385921;5726.914096288642;5730.5702072345575;5746.16680581305;5760.156749840011;5799.82600521985;3918.568957414625;3956.2680904032845;3997.180577981565;4007.6994225432627;4033.515978082649;5239.790408383775;5552.555795536428;5565.443455654402;5568.470995255444;5583.525400341016;5330.2804121443105;5368.489993450616;5450.672959622435;5460.726963651881;5482.698451585201;5430.456954304538;5464.693860214672;5484.670919744828;5513.255710479034;5524.309461317335;5289.418164002033;5454.2001139623035;5457.808976685797;5500.889982200253;5501.669899806025;5465.808696695596;5488.807891723771;5489;5503.2020357048805;5503.642263252876;5365.517103460707;5389.541888939327;5393.217983392307;5397.084035167616;5407.5944304176555 12:49:14 INFO - PROCESS | 31819 | |5;yui.html;690.5097380859593;697.3758486154888;740.3263030642116;743.7991742229598;748.1657951384207;15666.373345066198;16732;16831.158442077878;16837.74743378849;16869.240884160212;15041.097534147959;15396.461123860667;15407.460738874142;15421.537353879374;15444.69110617787;652.5921299188008;670.0853335197445;783.9059312882433;797.9042514898206;800.131856935226;799.2167675677839;801.9558924259163;802.3060053054095;802.458340620082;802.6067227058728;863.8790569320269;865.16079402979;865.6321063547967;866.5580553917516;866.7399780065987;693.9930600693979;708.0688894104288;827.3505298340774;851.0808327006818;855.7774978505571;620.6493331267865;626.680392999569;626.7367705563674;626.9905951410733;627.5575719118011;14240.786388204186;14295.57113286603;14305;14366.209858457885;14458.132512049311;13241.470341186341;13439.193648381128;13969.720605587932;13970.51103211393;14343.856561434357;11929.244766521708;11960.700982475508;12211.633650990485;12212.32832194229;12691.936540317332;4190.099128687347;4191.455110835603;4236.533981262059;4440.156370289635;4459.44256967879;64880.35119648837;66301.66849165723;67607.32392676109;82627;83185.58407207925;10711.678649640524;10729.517171727291;10785.83821242682;10809.513571889283;11191.552337906556;19308;19377.515562110777;19386;19408.611827763365;19423;8529.74410767678;8564.186402291773;8626.956865215634;8642.740717778477;8749.16882896124;4495.842645507391;4595.862124136282;4605.0559635274685;4684.1334353144775;4708.152532544106;3377.206356506219;3477.913052173716;3501.9124521887156;3509.3858574749315;3509.94735078974;585.420433770564;591.8727473593198;597.8774351257981;611.0681211153027;613.4632196827775;623.8570510297144;656.4289068510426;667.7796327212009;668.5192299057611;681.1961884975776;9426.245900327956;9996.750081248027;10010.949945250228;10021.248406369406;10126.189904807597;685.3420716112588;723.5188599581325;724.5580196080386;727.9235680253603;735.5255859970289;4544.681872268976;4616;4746.5490778376;4775.976120119447;4779.163646361872;83604.16395835964;125704.37147814203;126685;130176;130579.34710326388;106350;110458.44770776095;113285;114510.68616422947;115146.21394350097;94419;95732.52133739287;96489.42126944671;98739;98838.50580747338;8723.781905452415;9311.627534898527;9389.953050234704;9409.717708468757;9413.623455061721;53505.7324713374;53784.46215537794;53941;54316;55085.173722393396;74855.25144748483;78868.21131788609;79830.20169798228;79883;83055.584722076;11136.38749868765;11351.943240283746;11443;11542.826857596974;12158.148929575198;13405.262710551013;13482.595522134352;13880;13947.58157255284;13959.930200348934;12453.377331133588;12696.047796415121;12715;13002;13151.21092734439;11560.595379161688;11580.942095289469;11603.361815099914;11723.007088794755;11774.94112529432;11667.241629294069;11748.530058797895;11904.523819047488;11919.165658403828;12084.516619335129;9266;9360.95319523398;9367;9800.966068221625;9958.900410995797;9224.215941645036;9835.60657573689;10656;10684.83972740425;10705.8929410708 12:49:14 INFO - PROCESS | 31819 | __end_tp_report 12:49:14 INFO - PROCESS | 31819 | __start_cc_report 12:49:14 INFO - PROCESS | 31819 | _x_x_mozilla_cycle_collect,1143 12:49:14 INFO - PROCESS | 31819 | __end_cc_report 12:49:14 INFO - PROCESS | 31819 | __startTimestamp1474141754870__endTimestamp 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | ------- Summary: start ------- 12:49:14 INFO - PROCESS | 31819 | Number of tests: 6 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#0] dojo.html Cycles:165 Average:5195.38 Median:5380.81 stddev:575.30 (10.7%) stddev-sans-first:550.00 12:49:14 INFO - PROCESS | 31819 | Values: 2972.2 2997.9 3006.1 3016.9 3054.5 5082.3 5165.7 5173.9 5227.3 5258.7 5140.8 5200.2 5248.2 5256.3 5290.2 4712.5 4754.8 4790.5 4793.9 4864.0 4869.1 4900.3 4903.4 4916.9 4943.9 4696.2 4726.4 4731.1 4764.4 4823.0 4715.0 4728.1 4776.4 4802.2 4805.3 4674.9 4692.2 4724.1 4728.4 4772.4 5157.3 5194.9 5202.6 5208.9 5247.8 5477.3 5552.7 5559.4 5566.7 5641.9 5619.6 5623.9 5632.8 5639.9 5645.3 5110.7 5215.6 5217.9 5218.6 5282.9 5549.1 5558.8 5579.2 5634.2 5654.5 5340.3 5361.4 5364.0 5372.5 5378.8 5391.9 5413.8 5456.3 5464.7 5529.1 5425.9 5470.5 5473.0 5563.2 5578.6 5594.6 5599.0 5603.6 5607.3 5657.4 4341.4 4344.3 4348.7 4384.8 4394.3 3831.0 3840.2 3864.0 3875.6 3902.6 5293.9 5295.2 5305.9 5348.1 5380.8 5622.6 5639.9 5673.6 5676.6 5677.7 5158.9 5160.2 5204.0 5354.5 5421.9 4860.1 4911.0 4916.7 4966.5 4971.3 5825.4 5829.3 5830.9 5835.6 5886.2 5631.1 5634.5 5640.1 5645.4 5653.3 5644.2 5702.1 5733.3 5784.6 5816.2 5461.8 5519.1 5523.6 5582.4 5584.1 5561.7 5607.7 5616.3 5623.7 5636.6 5270.4 5316.3 5423.8 5494.8 5561.7 5420.4 5484.2 5485.3 5538.3 5549.5 5420.0 5429.8 5459.6 5462.9 5465.7 5353.1 5426.5 5481.9 5513.1 5579.4 5297.4 5384.3 5406.9 5413.7 5484.0 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#1] ext.html Cycles:180 Average:62502.14 Median:22761.59 stddev:128370.44 (564.0%) stddev-sans-first:128646.27 12:49:14 INFO - PROCESS | 31819 | Values: 543.5 598.4 610.8 610.9 632.9 32691.8 41783.8 42571.0 42575.4 42873.4 45271.0 45729.8 45762.0 45778.3 45784.8 8938.4 9077.0 9087.7 9089.9 9098.0 8847.6 8895.0 8967.7 8968.3 8978.9 8959.6 8990.9 9009.6 9019.8 9023.5 8949.5 8961.4 8970.2 8978.1 9013.0 8838.9 8916.8 8918.5 8921.2 8921.4 42019.6 42315.8 42334.8 42423.0 42446.8 3488.9 3494.7 3526.1 3775.2 4024.4 31729.8 31954.4 31954.8 31994.7 32211.4 3996.4 4084.9 4125.8 4154.3 4342.5 350534.0 354777.0 355341.3 356452.0 357961.0 58909.1 59108.7 59114.7 59158.4 59463.0 53846.5 55169.0 62177.1 62233.4 62294.0 15770.5 16450.4 16525.2 16762.8 16765.2 12453.2 12459.8 12475.6 12478.2 12479.5 16585.6 18909.2 18949.8 18955.8 19048.8 6202.5 7189.0 7244.9 7261.2 7317.9 932.4 1002.1 1018.0 1064.6 1088.4 32235.2 32391.2 32480.4 32550.8 33028.3 1066.6 1081.6 1103.3 1106.5 1146.3 16087.9 16799.2 16845.0 16964.6 17001.7 584515.0 589371.0 591466.0 593623.0 595910.0 57474.7 57898.0 57925.7 58016.0 58083.0 1279.8 1313.9 1336.8 1347.0 1347.3 29577.1 30821.7 31317.8 31406.8 32030.8 50911.7 51170.2 51294.7 51330.0 51331.5 470688.0 471549.0 472195.0 472566.0 472659.0 24825.9 25328.1 26332.0 26990.7 27766.0 46031.0 46205.5 46468.5 46646.0 47689.3 38761.0 39301.0 40154.2 40617.0 41521.0 39061.4 39693.4 40225.8 40233.4 41397.4 36322.0 36749.8 36973.8 37497.6 37724.8 33943.7 35298.5 35568.8 36283.3 36429.5 26547.5 27107.5 27471.9 27489.7 27596.7 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#2] jquery.html Cycles:180 Average:27761.36 Median:19755.88 stddev:76534.99 (387.4%) stddev-sans-first:76722.06 12:49:14 INFO - PROCESS | 31819 | Values: 372.1 387.6 388.5 399.6 407.5 17655.6 19619.9 19659.2 19694.1 19740.0 18433.6 18636.1 19133.6 19155.8 19308.6 5385.0 5436.2 5553.6 5571.1 5665.6 5518.5 5523.2 5556.7 5562.2 5564.5 5522.4 5532.1 5533.5 5537.4 5765.3 5849.1 5884.0 5885.5 5887.1 5890.6 5548.2 5694.7 5719.3 5751.5 5759.9 13910.2 14048.4 14066.7 14116.2 14200.6 2217.4 2302.8 2436.4 2700.2 2866.0 9765.8 9891.2 10373.4 10498.6 11437.0 1897.9 1914.5 1934.5 1943.4 1988.6 80264.2 82131.0 82754.0 84081.6 84202.0 10722.0 10838.4 11034.2 11124.4 11160.4 15645.1 15655.5 15672.7 15676.4 15699.8 7898.2 8118.0 8133.4 8331.4 8345.0 7705.9 7741.5 7755.7 7923.5 7961.4 4681.6 4774.8 4818.9 4851.3 4927.4 1903.8 1939.4 1971.7 1979.2 2028.3 833.4 924.7 932.7 982.4 1048.7 18532.2 19264.2 19311.0 19433.6 19438.2 937.1 968.5 973.5 983.1 989.5 10025.1 10288.7 10754.4 10809.4 10812.6 456502.0 458459.0 464483.0 464692.0 473068.0 85260.6 88811.0 89885.0 91563.5 93499.7 990.3 1013.2 1053.6 1075.9 1081.1 16614.0 16871.0 17256.9 17335.4 17435.7 30688.0 39123.2 40296.8 40535.4 42459.4 51732.2 52520.5 53645.0 54509.7 54794.0 7687.7 8415.9 8444.0 8634.3 8636.4 12504.7 13352.6 13463.0 13474.8 13555.0 10962.8 11441.0 11686.9 11916.9 12191.3 11367.6 11431.7 11475.7 11619.8 11690.7 11180.6 11898.8 12055.3 12080.3 12237.8 8662.4 8687.6 8704.2 8739.1 9076.2 9133.6 11168.3 11414.7 11447.5 12056.3 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#3] mootools.html Cycles:180 Average:5396.10 Median:4978.78 stddev:2972.75 (59.7%) stddev-sans-first:2965.14 12:49:14 INFO - PROCESS | 31819 | Values: 1299.0 1429.2 1443.9 1568.2 1714.8 7360.1 7953.7 8138.2 8264.2 8425.2 8259.4 8343.6 8347.4 8415.6 8425.9 2334.9 2348.6 2362.3 2369.5 2370.8 2299.2 2330.1 2356.4 2363.1 2364.0 2323.2 2323.3 2328.2 2369.3 2378.4 2286.1 2307.2 2316.5 2316.5 2322.9 2263.6 2281.6 2288.2 2319.6 2325.4 8066.3 8109.4 8133.7 8147.3 8308.6 4870.9 4923.9 5002.4 5007.7 5017.8 3138.3 3308.5 3364.2 3374.0 3390.4 1160.3 1209.2 1219.8 1223.2 1242.9 10810.7 10907.1 10908.8 10923.4 10935.9 4137.6 4296.5 4375.5 4378.9 4407.6 8850.8 8859.6 8865.1 8866.8 8867.2 4940.9 4945.3 4950.1 4951.3 4955.2 4025.3 4136.8 4434.5 4523.6 4653.6 3547.9 3570.8 3675.9 3691.6 3745.6 1138.0 1154.8 1160.8 1171.5 1182.4 833.0 937.6 968.1 983.2 997.3 5574.5 5621.6 5624.4 5840.4 5984.9 460.4 463.9 473.7 475.1 476.3 3926.1 4039.1 4057.7 4080.1 4155.1 9905.0 10209.3 10301.2 10309.1 10317.8 10078.1 10163.3 10189.2 10230.2 10249.5 7560.7 7613.2 7758.3 7770.6 7771.5 5642.4 5654.0 5805.9 5820.4 5825.2 9191.4 9289.2 9350.9 9404.7 9544.9 9069.4 9242.9 9342.9 9611.0 9667.6 6044.4 6241.8 6242.3 6858.1 6873.4 7452.6 7537.5 7683.0 7859.2 7941.5 7064.0 7098.1 7140.6 7144.3 7293.7 7045.4 7060.0 7073.9 7132.3 7216.7 6117.0 6235.5 6314.9 6406.1 6633.0 5195.4 5212.2 5236.5 5337.6 5343.8 5007.1 5097.6 5108.8 5147.0 5236.5 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#4] prototype.html Cycles:180 Average:4548.64 Median:5117.13 stddev:1336.37 (26.1%) stddev-sans-first:1301.82 12:49:14 INFO - PROCESS | 31819 | Values: 316.7 320.4 322.9 327.8 327.8 5070.8 5248.3 5275.9 5298.7 5312.9 5071.8 5153.2 5160.2 5166.8 5188.5 4110.0 4115.5 4122.6 4123.5 4163.6 3998.9 4109.2 4154.3 4160.0 4173.2 4060.9 4061.4 4083.0 4083.8 4092.4 4061.7 4081.8 4091.5 4099.0 4125.3 3593.2 3606.9 3607.4 3610.8 3617.9 5079.0 5107.2 5111.0 5116.3 5118.0 4607.7 4611.5 4623.5 4625.8 4630.7 5405.9 5426.8 5435.7 5444.3 5454.3 3731.6 3885.6 3891.8 3901.9 3933.0 5632.9 5663.4 5664.1 5671.6 5679.3 3978.4 4022.8 4031.8 4049.7 4067.4 4000.0 4065.7 4080.0 4094.6 4104.3 4598.0 4604.9 4610.9 4628.4 4630.0 5469.2 5471.5 5483.2 5488.0 5522.4 1285.8 1312.6 1364.1 1374.3 1386.7 885.4 886.4 893.6 901.7 911.8 4616.0 4620.0 4668.5 4681.9 4708.3 4819.9 4896.6 4902.0 4910.2 4923.4 4665.7 4667.2 4670.6 4695.0 4695.9 2736.5 2944.3 2962.3 2978.9 2983.4 5794.0 5797.3 5809.4 5810.0 5840.2 5458.5 5618.8 5657.5 5672.7 5702.1 5730.2 5732.8 5763.4 5772.7 5785.2 5558.6 5591.0 5606.4 5618.0 5632.8 5326.2 5515.8 5516.5 5523.6 5543.7 5726.9 5730.6 5746.2 5760.2 5799.8 3918.6 3956.3 3997.2 4007.7 4033.5 5239.8 5552.6 5565.4 5568.5 5583.5 5330.3 5368.5 5450.7 5460.7 5482.7 5430.5 5464.7 5484.7 5513.3 5524.3 5289.4 5454.2 5457.8 5500.9 5501.7 5465.8 5488.8 5489.0 5503.2 5503.6 5365.5 5389.5 5393.2 5397.1 5407.6 12:49:14 INFO - PROCESS | 31819 | 12:49:14 INFO - PROCESS | 31819 | [#5] yui.html Cycles:180 Average:21381.23 Median:4696.14 stddev:32360.03 (689.1%) stddev-sans-first:32413.51 12:49:14 INFO - PROCESS | 31819 | Values: 690.5 697.4 740.3 743.8 748.2 15666.4 16732.0 16831.2 16837.7 16869.2 15041.1 15396.5 15407.5 15421.5 15444.7 652.6 670.1 783.9 797.9 800.1 799.2 802.0 802.3 802.5 802.6 863.9 865.2 865.6 866.6 866.7 694.0 708.1 827.4 851.1 855.8 620.6 626.7 626.7 627.0 627.6 14240.8 14295.6 14305.0 14366.2 14458.1 13241.5 13439.2 13969.7 13970.5 14343.9 11929.2 11960.7 12211.6 12212.3 12691.9 4190.1 4191.5 4236.5 4440.2 4459.4 64880.4 66301.7 67607.3 82627.0 83185.6 10711.7 10729.5 10785.8 10809.5 11191.6 19308.0 19377.5 19386.0 19408.6 19423.0 8529.7 8564.2 8627.0 8642.7 8749.2 4495.8 4595.9 4605.1 4684.1 4708.2 3377.2 3477.9 3501.9 3509.4 3509.9 585.4 591.9 597.9 611.1 613.5 623.9 656.4 667.8 668.5 681.2 9426.2 9996.8 10010.9 10021.2 10126.2 685.3 723.5 724.6 727.9 735.5 4544.7 4616.0 4746.5 4776.0 4779.2 83604.2 125704.4 126685.0 130176.0 130579.3 106350.0 110458.4 113285.0 114510.7 115146.2 94419.0 95732.5 96489.4 98739.0 98838.5 8723.8 9311.6 9390.0 9409.7 9413.6 53505.7 53784.5 53941.0 54316.0 55085.2 74855.3 78868.2 79830.2 79883.0 83055.6 11136.4 11351.9 11443.0 11542.8 12158.1 13405.3 13482.6 13880.0 13947.6 13959.9 12453.4 12696.0 12715.0 13002.0 13151.2 11560.6 11580.9 11603.4 11723.0 11774.9 11667.2 11748.5 11904.5 11919.2 12084.5 9266.0 9361.0 9367.0 9801.0 9958.9 9224.2 9835.6 10656.0 10684.8 10705.9 12:49:14 INFO - PROCESS | 31819 | -------- Summary: end -------- 12:49:14 INFO - PROCESS | 31819 | 12:49:15 INFO - TEST-INFO | 31819: exit 0 12:49:15 INFO - TEST-OK | dromaeo_css | took 1116502ms 12:49:15 INFO - TEST-START | kraken 12:49:15 INFO - operating with platform_type : linux_ 12:49:15 INFO - Initialising browser for kraken test... 12:49:15 INFO - TEST-INFO | started process 32127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk32yN6/profile http://localhost:51063/getInfo.html) 12:49:20 INFO - PROCESS | 32127 | __metrics Screen width/height:1600/1200 12:49:20 INFO - PROCESS | 32127 | colorDepth:24 12:49:20 INFO - PROCESS | 32127 | Browser inner width/height: 1024/697 12:49:20 INFO - PROCESS | 32127 | __metrics 12:49:20 INFO - PROCESS | 32127 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:49:20 INFO - PROCESS | 32127 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:49:21 INFO - PROCESS | 32127 | console.error: PushService: 12:49:21 INFO - PROCESS | 32127 | stateChangeProcessEnqueue: Error transitioning state 12:49:21 INFO - PROCESS | 32127 | UnknownError 12:49:21 INFO - TEST-INFO | 32127: exit 0 12:49:21 INFO - Browser initialized. 12:49:21 INFO - Running cycle 1/1 for kraken test... 12:49:21 INFO - TEST-INFO | started process 32212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk32yN6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:33 INFO - PROCESS | 32212 | RSS: Main: 172867584 12:49:33 INFO - PROCESS | 32212 | 12:49:36 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51063/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:37 INFO - PROCESS | 32212 | RSS: Main: 232402944 12:49:37 INFO - PROCESS | 32212 | 12:49:40 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51063/tests/kraken/driver.html?testName=audio-dft) 12:49:40 INFO - PROCESS | 32212 | RSS: Main: 216530944 12:49:40 INFO - PROCESS | 32212 | 12:49:43 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51063/tests/kraken/driver.html?testName=audio-fft) 12:49:44 INFO - PROCESS | 32212 | RSS: Main: 298422272 12:49:44 INFO - PROCESS | 32212 | 12:49:46 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51063/tests/kraken/driver.html?testName=audio-oscillator) 12:49:47 INFO - PROCESS | 32212 | RSS: Main: 208408576 12:49:47 INFO - PROCESS | 32212 | 12:49:49 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:49 INFO - PROCESS | 32212 | RSS: Main: 212180992 12:49:49 INFO - PROCESS | 32212 | 12:49:52 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51063/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:53 INFO - PROCESS | 32212 | RSS: Main: 248541184 12:49:53 INFO - PROCESS | 32212 | 12:49:56 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51063/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:57 INFO - PROCESS | 32212 | RSS: Main: 247832576 12:49:57 INFO - PROCESS | 32212 | 12:50:00 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51063/tests/kraken/driver.html?testName=json-parse-financial) 12:50:00 INFO - PROCESS | 32212 | RSS: Main: 247762944 12:50:00 INFO - PROCESS | 32212 | 12:50:02 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:50:02 INFO - PROCESS | 32212 | RSS: Main: 209993728 12:50:02 INFO - PROCESS | 32212 | 12:50:04 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:50:05 INFO - PROCESS | 32212 | RSS: Main: 216584192 12:50:05 INFO - PROCESS | 32212 | 12:50:07 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:50:08 INFO - PROCESS | 32212 | RSS: Main: 222113792 12:50:08 INFO - PROCESS | 32212 | 12:50:10 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:50:11 INFO - PROCESS | 32212 | RSS: Main: 223318016 12:50:11 INFO - PROCESS | 32212 | 12:50:14 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:50:14 INFO - PROCESS | 32212 | RSS: Main: 220672000 12:50:14 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | Cycle 1(1): loaded http://localhost:51063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51063/tests/kraken/driver.html?testName=ai-astar) 12:50:16 INFO - PROCESS | 32212 | RSS: Main: 272175104 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | __start_tp_report 12:50:16 INFO - PROCESS | 32212 | _x_x_mozilla_page_load 12:50:16 INFO - PROCESS | 32212 | _x_x_mozilla_page_load_details 12:50:16 INFO - PROCESS | 32212 | |i|pagename|runs| 12:50:16 INFO - PROCESS | 32212 | |0;ai-astar;112;115;113;115;114;115;113;115;114;114 12:50:16 INFO - PROCESS | 32212 | |1;audio-beat-detection;142;130;121;166;119;120;118;121;151;116 12:50:16 INFO - PROCESS | 32212 | |2;audio-dft;162;160;162;161;160;160;161;163;163;162 12:50:16 INFO - PROCESS | 32212 | |3;audio-fft;75;76;76;111;76;76;75;76;102;75 12:50:16 INFO - PROCESS | 32212 | |4;audio-oscillator;82;82;110;83;83;82;115;81;82;108 12:50:16 INFO - PROCESS | 32212 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;118;117 12:50:16 INFO - PROCESS | 32212 | |6;imaging-darkroom;146;147;145;146;146;146;145;146;146;147 12:50:16 INFO - PROCESS | 32212 | |7;imaging-desaturate;96;96;95;95;95;95;95;94;95;95 12:50:16 INFO - PROCESS | 32212 | |8;json-parse-financial;50;51;50;49;51;51;53;52;52;51 12:50:16 INFO - PROCESS | 32212 | |9;json-stringify-tinderbox;61;61;60;61;61;60;61;61;61;62 12:50:16 INFO - PROCESS | 32212 | |10;stanford-crypto-aes;78;79;79;80;80;78;79;78;83;77 12:50:16 INFO - PROCESS | 32212 | |11;stanford-crypto-ccm;127;135;125;138;123;141;124;131;185;127 12:50:16 INFO - PROCESS | 32212 | |12;stanford-crypto-pbkdf2;168;165;168;166;165;165;164;168;163;163 12:50:16 INFO - PROCESS | 32212 | |13;stanford-crypto-sha256-iterative;77;65;67;66;65;67;67;66;64;65 12:50:16 INFO - PROCESS | 32212 | __end_tp_report 12:50:16 INFO - PROCESS | 32212 | __start_cc_report 12:50:16 INFO - PROCESS | 32212 | _x_x_mozilla_cycle_collect,1654 12:50:16 INFO - PROCESS | 32212 | __end_cc_report 12:50:16 INFO - PROCESS | 32212 | __startTimestamp1474141816623__endTimestamp 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | ------- Summary: start ------- 12:50:16 INFO - PROCESS | 32212 | Number of tests: 14 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:1.05 (0.9%) stddev-sans-first:0.83 12:50:16 INFO - PROCESS | 32212 | Values: 112.0 115.0 113.0 115.0 114.0 115.0 113.0 115.0 114.0 114.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:125.50 stddev:17.00 (13.5%) stddev-sans-first:17.51 12:50:16 INFO - PROCESS | 32212 | Values: 142.0 130.0 121.0 166.0 119.0 120.0 118.0 121.0 151.0 116.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.22 12:50:16 INFO - PROCESS | 32212 | Values: 162.0 160.0 162.0 161.0 160.0 160.0 161.0 163.0 163.0 162.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:13.20 (17.4%) stddev-sans-first:13.77 12:50:16 INFO - PROCESS | 32212 | Values: 75.0 76.0 76.0 111.0 76.0 76.0 75.0 76.0 102.0 75.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:82.00 stddev:14.05 (17.1%) stddev-sans-first:14.54 12:50:16 INFO - PROCESS | 32212 | Values: 82.0 82.0 110.0 83.0 83.0 82.0 115.0 81.0 82.0 108.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:50:16 INFO - PROCESS | 32212 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 12:50:16 INFO - PROCESS | 32212 | Values: 146.0 147.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 147.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 12:50:16 INFO - PROCESS | 32212 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.15 (2.3%) stddev-sans-first:1.17 12:50:16 INFO - PROCESS | 32212 | Values: 50.0 51.0 50.0 49.0 51.0 51.0 53.0 52.0 52.0 51.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 12:50:16 INFO - PROCESS | 32212 | Values: 61.0 61.0 60.0 61.0 61.0 60.0 61.0 61.0 61.0 62.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:1.66 (2.1%) stddev-sans-first:1.72 12:50:16 INFO - PROCESS | 32212 | Values: 78.0 79.0 79.0 80.0 80.0 78.0 79.0 78.0 83.0 77.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:133.00 stddev:18.41 (13.8%) stddev-sans-first:19.26 12:50:16 INFO - PROCESS | 32212 | Values: 127.0 135.0 125.0 138.0 123.0 141.0 124.0 131.0 185.0 127.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:1.96 (1.2%) stddev-sans-first:1.86 12:50:16 INFO - PROCESS | 32212 | Values: 168.0 165.0 168.0 166.0 165.0 165.0 164.0 168.0 163.0 163.0 12:50:16 INFO - PROCESS | 32212 | 12:50:16 INFO - PROCESS | 32212 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.50 stddev:3.70 (5.6%) stddev-sans-first:1.09 12:50:16 INFO - PROCESS | 32212 | Values: 77.0 65.0 67.0 66.0 65.0 67.0 67.0 66.0 64.0 65.0 12:50:16 INFO - PROCESS | 32212 | -------- Summary: end -------- 12:50:16 INFO - PROCESS | 32212 | 12:50:17 INFO - TEST-INFO | 32212: exit 0 12:50:17 INFO - TEST-OK | kraken | took 62074ms 12:50:17 INFO - SUITE-END | took 1178s 12:50:18 INFO - Completed test suite (00:19:38) 12:50:18 INFO - javascript benchmark 12:50:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.2123637236127, 2997.925051873703, 3006.068118883146, 3016.86424110915, 3054.5418187271907, 5082.288479612848, 5165.690058596487, 5173.9482605173935, 5227.3465816772905, 5258.684478931262, 5140.820071297505, 5200.167973124301, 5248.239005344232, 5256.316678831747, 5290.232916227144, 4712.528747125277, 4754.8098076077085, 4790.496997815214, 4793.9280910786365, 4863.951360486403, 4869.050535145648, 4900.289458028584, 4903.387076615423, 4916.926246106311, 4943.876403089916, 4696.154692155411, 4726.385569875894, 4731.124741922756, 4764.404449443819, 4822.97588512057, 4714.976425117887, 4728.078024785168, 4776.379070720819, 4802.207635740098, 4805.327254184417, 4674.906501869978, 4692.249240121599, 4724.126036683225, 4728.40894888139, 4772.427308722976, 5157.252198431244, 5194.870128246786, 5202.635815492917, 5208.869778255536, 5247.816326428595, 5477.260569823103, 5552.666839989573, 5559.416261292547, 5566.721663916788, 5641.943580564182, 5619.6066275360745, 5623.943760562423, 5632.831015069513, 5639.8590035249035, 5645.294338207724, 5110.66780659256, 5215.582753379758, 5217.895642087175, 5218.608604354688, 5282.894342113174, 5549.084401073858, 5558.805441809517, 5579.163125531203, 5634.1830434586755, 5654.491095801398, 5340.332458442695, 5361.383440904346, 5364.0, 5372.543333816591, 5378.784848606012, 5391.892162156735, 5413.810516631899, 5456.263404440429, 5464.726763661801, 5529.115341545334, 5425.864353391118, 5470.507654311131, 5473.042217611902, 5563.221149039137, 5578.63738856973, 5594.636348637326, 5599.048161812501, 5603.579731520153, 5607.299087614048, 5657.4059723728415, 4341.435613370241, 4344.261475549164, 4348.7390756554705, 4384.846530371422, 4394.2749446340995, 3831.04223944014, 3840.2319536092336, 3864.0146762575923, 3875.593062728373, 3902.6097390260747, 5293.9205911910585, 5295.232191332235, 5305.920411193835, 5348.090824559834, 5380.811671591476, 5622.57830662702, 5639.915401268984, 5673.602847800615, 5676.631018983753, 5677.716114194273, 5158.92261616076, 5160.174372100446, 5204.0372531081875, 5354.518093371616, 5421.891562168735, 4860.1251774680895, 4910.950890491049, 4916.70499770015, 4966.478519755446, 4971.304017437562, 5825.359210486843, 5829.271341082364, 5830.854228644318, 5835.5915085944425, 5886.205362276123, 5631.1271752878365, 5634.549236061023, 5640.069388551007, 5645.350784659817, 5653.293338332708, 5644.238027866186, 5702.059160238514, 5733.340665823317, 5784.595078344475, 5816.185733997159, 5461.781528738806, 5519.144532597454, 5523.585731070106, 5582.358028826737, 5584.106542953108, 5561.6663000220115, 5607.663540187602, 5616.297962754656, 5623.662580245198, 5636.605437619327, 5270.4202537721585, 5316.3354580677415, 5423.810166644149, 5494.80768173128, 5561.694106824163, 5420.430854760193, 5484.177373394023, 5485.259489968803, 5538.307711536058, 5549.500544950974, 5419.972900135474, 5429.7556609953435, 5459.590530710292, 5462.9180562292395, 5465.726713664221, 5353.0632139374675, 5426.511613954763, 5481.917771233355, 5513.062779327443, 5579.386267510652, 5297.417284098669, 5384.326959130109, 5406.945930540644, 5413.729313534386, 5483.972580137074], "value": 5157.2005085777955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.52068696331, 598.4314900844198, 610.7526451787026, 610.9144719739237, 632.8639342541353, 32691.836540817294, 41783.79108104459, 42571.0, 42575.361369579405, 42873.35689964653, 45271.0, 45729.7713511432, 45762.0, 45778.31332530015, 45784.77107614466, 8938.37431379802, 9076.954615226914, 9087.681931132413, 9089.909100908972, 9097.954510227473, 8847.601857916408, 8895.0, 8967.730968070968, 8968.282537396992, 8978.86531702025, 8959.64161433545, 8990.910090899106, 9009.594568244445, 9019.774505637346, 9023.45859248444, 8949.507777072258, 8961.372703910729, 8970.237529809974, 8978.147076027766, 9013.00856905743, 8838.911610883873, 8916.821663566692, 8918.509481978488, 8921.241694455914, 8921.375503714742, 42019.57980420218, 42315.78842105801, 42334.78832605848, 42423.0, 42446.787766060974, 3488.9127771805656, 3494.702950249232, 3526.118470382404, 3775.2260786538695, 4024.376221685614, 31729.8413507931, 31954.360912781845, 31954.84022579872, 31994.6800531994, 32211.35577288441, 3996.3805610130185, 4084.8774536763945, 4125.793710314502, 4154.252234597771, 4342.522322544518, 350534.0, 354777.0, 355341.25222468877, 356452.0, 357960.9999999974, 58909.11636325459, 59108.70445647744, 59114.70442647759, 59158.40841591615, 59463.0, 53846.46153538493, 55169.0, 62177.067343989875, 62233.37766622366, 62294.0, 15770.526884193492, 16450.424235151953, 16525.173741312887, 16762.83237167637, 16765.24556394965, 12453.1905426147, 12459.75080498403, 12475.625731228078, 12478.18891772032, 12479.500819967281, 16585.585360365847, 18909.24363025491, 18949.81050189508, 18955.81044189568, 19048.80951190498, 6202.503799696104, 7188.964055179691, 7244.877044058179, 7261.237570055174, 7317.890231646529, 932.4265576670434, 1002.1081237698454, 1017.9616790873267, 1064.6060957445795, 1088.374184843718, 32235.194120147185, 32391.190220244684, 32480.35039299248, 32550.83724581362, 33028.3394332112, 1066.594694016269, 1081.6376513867942, 1103.27184058521, 1106.5407855739938, 1146.2893006336126, 16087.919560402122, 16799.16004199785, 16845.0, 16964.575885602957, 17001.659966800595, 584515.0, 589371.0, 591466.0, 593623.0, 595910.0, 57474.7126264366, 57898.0, 57925.71037144872, 58016.0, 58083.0, 1279.8080287956882, 1313.8554758976504, 1336.8462626797848, 1346.9528566500321, 1347.3330701302918, 29577.112686619435, 30821.691783081882, 31317.8434107828, 31406.842965785025, 32030.839845800623, 50911.74544127404, 51170.232446512586, 51294.74352628362, 51330.0, 51331.48668513267, 470688.0, 471549.0, 472195.0, 472566.0, 472659.0, 24825.87587062053, 25328.11351602685, 26332.0, 26990.73009269882, 27766.0, 46031.0, 46205.537944620126, 46468.53531464777, 46646.0, 47689.28466073081, 38761.0, 39301.0, 40154.1969160621, 40617.0, 41521.0, 39061.41407878941, 39693.404598931615, 40225.798871005456, 40233.396499053124, 41397.37903931383, 36322.0, 36749.81625091857, 36973.81513092417, 37497.62502374942, 37724.81137594295, 33943.66056339405, 35298.47052294269, 35568.77249100381, 36283.27433451369, 36429.45355819612, 26547.469050619267, 27107.457850843268, 27471.901123954816, 27489.72510274872, 27596.724032759415], "value": 19966.22385700565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.116223968076, 387.56592616269774, 388.53764020815225, 399.6423201234893, 407.5150570820723, 17655.646887062267, 19619.901900490495, 19659.21363145476, 19694.113764880596, 19740.0, 18433.631327373445, 18636.068196590186, 19133.61732765344, 19155.80844191558, 19308.61382772344, 5384.973075134639, 5436.157395603688, 5553.611247212697, 5571.13647384656, 5665.575081868856, 5518.475744804237, 5523.226748255227, 5556.749946252408, 5562.16567514874, 5564.4713752193475, 5522.392536820947, 5532.114861622142, 5533.474319939599, 5537.446255374471, 5765.308163020422, 5849.093390524455, 5884.0, 5885.529157667376, 5887.1463637772495, 5890.617109887844, 5548.167774833767, 5694.6867922264255, 5719.25649665545, 5751.482366587027, 5759.856003599902, 13910.234937078558, 14048.367823447968, 14066.718665626733, 14116.223607701675, 14200.573982780534, 2217.3569664797187, 2302.804261637763, 2436.378723425533, 2700.2313484667575, 2865.9957389269657, 9765.804683906354, 9891.159251463634, 10373.377597344184, 10498.632547860789, 11437.027852632536, 1897.9051047447572, 1914.5117994911348, 1934.4970307719952, 1943.37811900192, 1988.5724569217687, 80264.19735802626, 82131.0, 82754.0, 84081.57959210227, 84202.0, 10722.0, 10838.403887786248, 11034.172437067253, 11124.382863513305, 11160.386178760084, 15645.139517326656, 15655.452059177875, 15672.686546269239, 15676.372945082298, 15699.843001569838, 7898.170692077249, 8117.959410202911, 8133.430659853753, 8331.416800823885, 8345.0, 7705.922940770521, 7741.522712731984, 7755.68977240904, 7923.484973476706, 7961.402894782915, 4681.648876334289, 4774.809007639725, 4818.855434336975, 4851.320815085891, 4927.408710954708, 1903.8096190381127, 1939.3903060459784, 1971.684530475117, 1979.2281010405952, 2028.3407892435018, 833.3624777045565, 924.7272054743732, 932.6735642525061, 982.40564458503, 1048.7168464514539, 18532.166052527667, 19264.240703379797, 19311.0, 19433.611327773364, 19438.22247110128, 937.1331518345503, 968.5254225429488, 973.5083782689696, 983.1201075037801, 989.5151375825938, 10025.147862431775, 10288.742781430525, 10754.40850753216, 10809.405482698527, 10812.56749730002, 456502.0, 458459.0, 464483.0, 464692.0, 473068.0, 85260.57369713111, 88811.0, 89885.0, 91563.54218228866, 93499.72750953684, 990.3117333453181, 1013.2400699475394, 1053.5943661690305, 1075.8799481503702, 1081.1350919264557, 16614.0, 16871.047566675537, 17256.913715431845, 17335.393261235797, 17435.738463923306, 30688.0, 39123.217535649695, 40296.798516007235, 40535.391969121076, 42459.363109554, 51732.224016639026, 52520.47479525156, 53645.0, 54509.72745136249, 54794.0, 7687.692492300469, 8415.8737618937, 8444.0, 8634.266087382643, 8636.438631488933, 12504.687382815138, 13352.599422017354, 13463.0, 13474.797878032034, 13555.0, 10962.835557466804, 11441.0, 11686.94156529212, 11916.880831191924, 12191.329476878856, 11367.602133925273, 11431.657050288504, 11475.655730328104, 11619.825702614298, 11690.70773230676, 11180.608678696532, 11898.821517677068, 12055.336956467128, 12080.297927269117, 12237.816432753338, 8662.393632445921, 8687.609057592297, 8704.173103555153, 8739.12608739121, 9076.228520575825, 9133.588988495401, 11168.29234236926, 11414.657560273205, 11447.542098316308, 12056.336901470504], "value": 9018.97959082896, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.0, 1429.2210745143896, 1443.8844892408608, 1568.1767072287055, 1714.7599336092958, 7360.07999000125, 7953.72161974331, 8138.226868447503, 8264.214899584545, 8425.157484251571, 8259.380546459024, 8343.582820858963, 8347.373946953972, 8415.621297041633, 8425.873611895826, 2334.9182778602753, 2348.58899692554, 2362.338545207345, 2369.490559529701, 2370.78662920338, 2299.2067736630834, 2330.067972810872, 2356.4108972756812, 2363.0665886974634, 2363.9763602364014, 2323.1520495019367, 2323.256557901472, 2328.196772113618, 2369.2536850892, 2378.357843382286, 2286.1084177170915, 2307.157887371106, 2316.4672125411166, 2316.51353215824, 2322.941926451835, 2263.592553340398, 2281.566502364562, 2288.2448791898632, 2319.6404557293477, 2325.4070212095976, 8066.274035336848, 8109.4323397362205, 8133.674653013873, 8147.348212143014, 8308.626111824982, 4870.902581948341, 4923.876903077452, 5002.374703162105, 5007.69953802773, 5017.774200161001, 3138.2938838761324, 3308.470644696861, 3364.2094107884627, 3374.021533755208, 3390.4066788312193, 1160.3328086350298, 1209.246359084514, 1219.8170274458814, 1223.1988047828597, 1242.9254244745255, 10810.67567972962, 10907.127429805676, 10908.836367454494, 10923.39921304328, 10935.945320273428, 4137.586241375839, 4296.505901821331, 4375.453068366454, 4378.934315985262, 4407.559244075567, 8850.822983540293, 8859.64561417536, 8865.113488651212, 8866.822663546822, 8867.246284065926, 4940.876478088005, 4945.282933974553, 4950.084234416572, 4951.282064100686, 4955.182394904872, 4025.295573274665, 4136.834526618902, 4434.490033646108, 4523.638108951276, 4653.627709783209, 3547.8758243461357, 3570.7500474966505, 3675.9264814703556, 3691.6123807000417, 3745.6067112952746, 1137.9601713939971, 1154.7863645225493, 1160.8200728887036, 1171.478691982077, 1182.3733421286731, 832.9708460203985, 937.6249500199838, 968.070652173921, 983.233078199006, 997.3218211616025, 5574.470425309589, 5621.634593751393, 5624.446193524154, 5840.3575606683235, 5984.940150598525, 460.4198709625895, 463.8979424526599, 473.66606542387535, 475.113912553086, 476.25703901913124, 3926.1166237596312, 4039.091204478969, 4057.736247143926, 4080.1227736036303, 4155.065110350147, 9904.950475247577, 10209.336393134423, 10301.227407944434, 10309.07218350352, 10317.793644127225, 10078.09297163244, 10163.339382940085, 10189.184865210766, 10230.181585473145, 10249.538770755335, 7560.735374261874, 7613.238676132453, 7758.340541053961, 7770.572618506037, 7771.533707977539, 5642.379338272704, 5654.0, 5805.8548536286935, 5820.359760426434, 5825.184474173704, 9191.402558833654, 9289.210417114351, 9350.906490935004, 9404.717858464257, 9544.856827147738, 9069.410488318239, 9242.861357079515, 9342.906570934203, 9610.95194524023, 9667.613295468384, 6044.365341639064, 6241.751649670175, 6242.250929888442, 6858.142732158481, 6873.415759660485, 7452.590107544137, 7537.54774713519, 7682.961585192038, 7859.17478664732, 7941.523508589503, 7064.0, 7098.148222213367, 7140.571565706075, 7144.2855714430225, 7293.708251669874, 7045.365917067488, 7059.964700176466, 7073.858522829411, 7132.251113633201, 7216.7113315466795, 6117.0, 6235.532335074979, 6314.8737025258315, 6406.071119687713, 6633.038209459505, 5195.350581177353, 5212.166053431432, 5236.502532259429, 5337.626366154332, 5343.812966546152, 5007.148784706682, 5097.643164978416, 5108.821191258288, 5146.974265128651, 5236.528712415901], "value": 4321.596594238278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.7165386978654, 320.3992514036182, 322.87246537617636, 327.75090930892515, 327.77219832216605, 5070.771815268313, 5248.317718696569, 5275.920861187075, 5298.682079075258, 5312.867178320539, 5071.771770270338, 5153.2270159476, 5160.225966105086, 5166.793328266875, 5188.455212202721, 4109.958900410988, 4115.464989551354, 4122.649574786132, 4123.484564429446, 4163.605926592221, 3998.880313512221, 4109.198706252282, 4154.258189594868, 4159.958400416002, 4173.227952828721, 4060.8578699745517, 4061.3501839705655, 4083.0, 4083.7549747015128, 4092.4475195848477, 4061.735987160825, 4081.795910204493, 4091.488563929509, 4098.979505102485, 4125.29869922111, 3593.2274560969254, 3606.855725770966, 3607.3867442534834, 3610.8194590270646, 3617.9095522611883, 5078.984203159383, 5107.157319042353, 5110.9744451277875, 5116.283720279164, 5117.974410127926, 4607.654425918036, 4611.492735799059, 4623.468301145378, 4625.791839367236, 4630.699004564693, 5405.945940540623, 5426.810061647863, 5435.728213589305, 5444.346678398578, 5454.263674403945, 3731.626837316274, 3885.5920128386397, 3891.7664940103687, 3901.8634347797974, 3932.960670393288, 5632.915506267409, 5663.405342439067, 5664.0937450007805, 5671.602987790856, 5679.290088738908, 3978.3634618460906, 4022.8390864365683, 4031.7580945143386, 4049.655779258737, 4067.3695577185586, 3999.9800000999808, 4065.6747460203114, 4079.9592004079577, 4094.61101195386, 4104.302268614343, 4598.034412773288, 4604.907901841944, 4610.907781844411, 4628.3983082199775, 4629.981404091094, 5469.206964990054, 5471.534919531805, 5483.150111732688, 5488.0, 5522.3649280333275, 1285.7749893768553, 1312.6193403912948, 1364.1201425080767, 1374.305975482375, 1386.6602682342689, 885.4465958775766, 886.3751055505853, 893.5978809535735, 901.715959472764, 911.8495448250965, 4615.95384046162, 4620.0, 4668.486466488684, 4681.859544213679, 4708.340832283483, 4819.879503012453, 4896.5837903777865, 4901.950980490221, 4910.170067347474, 4923.433805112386, 4665.690483210832, 4667.17979987805, 4670.6497012724185, 4694.990577025956, 4695.906081878412, 2736.480068786924, 2944.2933695912825, 2962.252271160026, 2978.8978078110804, 2983.4331477019296, 5794.0, 5797.333306669702, 5809.448102430347, 5809.970950145307, 5840.211571437886, 5458.45966344161, 5618.803341883096, 5657.472482429804, 5672.744726487235, 5702.087665973508, 5730.169125476783, 5732.7706891723865, 5763.366029736726, 5772.682502462405, 5785.190073389729, 5558.610897237155, 5591.0, 5606.439356064524, 5618.033688209094, 5632.831015069554, 5326.174442961347, 5515.766114723543, 5516.538117398813, 5523.558115350843, 5543.72281385921, 5726.914096288642, 5730.5702072345575, 5746.16680581305, 5760.156749840011, 5799.82600521985, 3918.568957414625, 3956.2680904032845, 3997.180577981565, 4007.6994225432627, 4033.515978082649, 5239.790408383775, 5552.555795536428, 5565.443455654402, 5568.470995255444, 5583.525400341016, 5330.2804121443105, 5368.489993450616, 5450.672959622435, 5460.726963651881, 5482.698451585201, 5430.456954304538, 5464.693860214672, 5484.670919744828, 5513.255710479034, 5524.309461317335, 5289.418164002033, 5454.2001139623035, 5457.808976685797, 5500.889982200253, 5501.669899806025, 5465.808696695596, 5488.807891723771, 5489.0, 5503.2020357048805, 5503.642263252876, 5365.517103460707, 5389.541888939327, 5393.217983392307, 5397.084035167616, 5407.5944304176555], "value": 4119.5156197530105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.5097380859593, 697.3758486154888, 740.3263030642116, 743.7991742229598, 748.1657951384207, 15666.373345066198, 16732.0, 16831.158442077878, 16837.74743378849, 16869.240884160212, 15041.097534147959, 15396.461123860667, 15407.460738874142, 15421.537353879374, 15444.69110617787, 652.5921299188008, 670.0853335197445, 783.9059312882433, 797.9042514898206, 800.131856935226, 799.2167675677839, 801.9558924259163, 802.3060053054095, 802.458340620082, 802.6067227058728, 863.8790569320269, 865.16079402979, 865.6321063547967, 866.5580553917516, 866.7399780065987, 693.9930600693979, 708.0688894104288, 827.3505298340774, 851.0808327006818, 855.7774978505571, 620.6493331267865, 626.680392999569, 626.7367705563674, 626.9905951410733, 627.5575719118011, 14240.786388204186, 14295.57113286603, 14305.0, 14366.209858457885, 14458.132512049311, 13241.470341186341, 13439.193648381128, 13969.720605587932, 13970.51103211393, 14343.856561434357, 11929.244766521708, 11960.700982475508, 12211.633650990485, 12212.32832194229, 12691.936540317332, 4190.099128687347, 4191.455110835603, 4236.533981262059, 4440.156370289635, 4459.44256967879, 64880.35119648837, 66301.66849165723, 67607.32392676109, 82627.0, 83185.58407207925, 10711.678649640524, 10729.517171727291, 10785.83821242682, 10809.513571889283, 11191.552337906556, 19308.0, 19377.515562110777, 19386.0, 19408.611827763365, 19423.0, 8529.74410767678, 8564.186402291773, 8626.956865215634, 8642.740717778477, 8749.16882896124, 4495.842645507391, 4595.862124136282, 4605.0559635274685, 4684.1334353144775, 4708.152532544106, 3377.206356506219, 3477.913052173716, 3501.9124521887156, 3509.3858574749315, 3509.94735078974, 585.420433770564, 591.8727473593198, 597.8774351257981, 611.0681211153027, 613.4632196827775, 623.8570510297144, 656.4289068510426, 667.7796327212009, 668.5192299057611, 681.1961884975776, 9426.245900327956, 9996.750081248027, 10010.949945250228, 10021.248406369406, 10126.189904807597, 685.3420716112588, 723.5188599581325, 724.5580196080386, 727.9235680253603, 735.5255859970289, 4544.681872268976, 4616.0, 4746.5490778376, 4775.976120119447, 4779.163646361872, 83604.16395835964, 125704.37147814203, 126685.0, 130176.0, 130579.34710326388, 106350.0, 110458.44770776095, 113285.0, 114510.68616422947, 115146.21394350097, 94419.0, 95732.52133739287, 96489.42126944671, 98739.0, 98838.50580747338, 8723.781905452415, 9311.627534898527, 9389.953050234704, 9409.717708468757, 9413.623455061721, 53505.7324713374, 53784.46215537794, 53941.0, 54316.0, 55085.173722393396, 74855.25144748483, 78868.21131788609, 79830.20169798228, 79883.0, 83055.584722076, 11136.38749868765, 11351.943240283746, 11443.0, 11542.826857596974, 12158.148929575198, 13405.262710551013, 13482.595522134352, 13880.0, 13947.58157255284, 13959.930200348934, 12453.377331133588, 12696.047796415121, 12715.0, 13002.0, 13151.21092734439, 11560.595379161688, 11580.942095289469, 11603.361815099914, 11723.007088794755, 11774.94112529432, 11667.241629294069, 11748.530058797895, 11904.523819047488, 11919.165658403828, 12084.516619335129, 9266.0, 9360.95319523398, 9367.0, 9800.966068221625, 9958.900410995797, 9224.215941645036, 9835.60657573689, 10656.0, 10684.83972740425, 10705.8929410708], "value": 7396.957563709072, "unit": "score"}], "name": "dromaeo_css", "value": 7045.469798727813, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 113.0, 115.0, 114.0, 115.0, 113.0, 115.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 130.0, 121.0, 166.0, 119.0, 120.0, 118.0, 121.0, 151.0, 116.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 161.0, 160.0, 160.0, 161.0, 163.0, 163.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 111.0, 76.0, 76.0, 75.0, 76.0, 102.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 110.0, 83.0, 83.0, 82.0, 115.0, 81.0, 82.0, 108.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 49.0, 51.0, 51.0, 53.0, 52.0, 52.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 62.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 80.0, 80.0, 78.0, 79.0, 78.0, 83.0, 77.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 135.0, 125.0, 138.0, 123.0, 141.0, 124.0, 131.0, 185.0, 127.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 168.0, 166.0, 165.0, 165.0, 164.0, 168.0, 163.0, 163.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 65.0, 67.0, 66.0, 65.0, 67.0, 67.0, 66.0, 64.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1495.6, "alertThreshold": 2.0}]} 12:50:18 INFO - Return code: 0 12:50:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:50:18 INFO - # TBPL SUCCESS # 12:50:18 INFO - Running post-action listener: _resource_record_post_action 12:50:18 INFO - [mozharness: 2016-09-17 19:50:18.458415Z] Finished run-tests step (success) 12:50:18 INFO - Running post-run listener: _resource_record_post_run 12:50:18 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 416694272; Read time: 16; Write time: 259556 12:50:18 INFO - TinderboxPrint: CPU usage
13.1% 12:50:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 12:50:18 INFO - TinderboxPrint: I/O write bytes / time
416,694,272 / 259,556 12:50:18 INFO - TinderboxPrint: CPU idle
8,290.4 (86.9%) 12:50:18 INFO - TinderboxPrint: CPU user
1,199.8 (12.6%) 12:50:18 INFO - TinderboxPrint: Swap in / out
0 / 0 12:50:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 120569856; Read time: 0; Write time: 90476 12:50:18 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 295780352; Read time: 0; Write time: 169076 12:50:19 INFO - Running post-run listener: _upload_blobber_files 12:50:19 INFO - Blob upload gear active. 12:50:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:50:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:50:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:50:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:50:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:50:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:23 INFO - (blobuploader) - INFO - Done attempting. 12:50:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:50:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:24 INFO - (blobuploader) - INFO - Done attempting. 12:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:25 INFO - (blobuploader) - INFO - Done attempting. 12:50:25 INFO - (blobuploader) - INFO - Iteration through files over. 12:50:25 INFO - Return code: 0 12:50:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:50:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:50:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a6f49d89212081f63c3511109fe31f45f033a87fef347d7d245ebcd0ac0861d8ef35fd3b59bf0b5b1a9727953fafd147c0d6f232fb53dd3d980915a6275bfce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/077c29b69ae7493b46ea4d1183be8726f8fc4ce3ac151bdb9e078e51c5090c88a7293fe9ad7e5f7d4df976aa7ae0b414a48d40eb00f10ee8512ace13f1d50e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78af3ff8f28b1106afed7b5c450a5814e0306fc397691146e19b7bb5d55c5858a545f55928a5b51b8f2ab3d52cc86c561b14c32dc4178946b68dd02a86d75b6"} 12:50:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:50:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:50:25 INFO - Contents: 12:50:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a6f49d89212081f63c3511109fe31f45f033a87fef347d7d245ebcd0ac0861d8ef35fd3b59bf0b5b1a9727953fafd147c0d6f232fb53dd3d980915a6275bfce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/077c29b69ae7493b46ea4d1183be8726f8fc4ce3ac151bdb9e078e51c5090c88a7293fe9ad7e5f7d4df976aa7ae0b414a48d40eb00f10ee8512ace13f1d50e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78af3ff8f28b1106afed7b5c450a5814e0306fc397691146e19b7bb5d55c5858a545f55928a5b51b8f2ab3d52cc86c561b14c32dc4178946b68dd02a86d75b6"} 12:50:25 INFO - Running post-run listener: copy_logs_to_upload_dir 12:50:25 INFO - Copying logs to upload dir... 12:50:25 INFO - mkdir: /builds/slave/test/build/upload/logs 12:50:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.843083 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-17 12:50:24.459845) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 12:50:24.463154) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a6f49d89212081f63c3511109fe31f45f033a87fef347d7d245ebcd0ac0861d8ef35fd3b59bf0b5b1a9727953fafd147c0d6f232fb53dd3d980915a6275bfce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/077c29b69ae7493b46ea4d1183be8726f8fc4ce3ac151bdb9e078e51c5090c88a7293fe9ad7e5f7d4df976aa7ae0b414a48d40eb00f10ee8512ace13f1d50e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78af3ff8f28b1106afed7b5c450a5814e0306fc397691146e19b7bb5d55c5858a545f55928a5b51b8f2ab3d52cc86c561b14c32dc4178946b68dd02a86d75b6"} build_url:https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011528 build_url: 'https://queue.taskcluster.net/v1/task/DPm6ggBdQ3qbw3wu8JXx7g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a6f49d89212081f63c3511109fe31f45f033a87fef347d7d245ebcd0ac0861d8ef35fd3b59bf0b5b1a9727953fafd147c0d6f232fb53dd3d980915a6275bfce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/077c29b69ae7493b46ea4d1183be8726f8fc4ce3ac151bdb9e078e51c5090c88a7293fe9ad7e5f7d4df976aa7ae0b414a48d40eb00f10ee8512ace13f1d50e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78af3ff8f28b1106afed7b5c450a5814e0306fc397691146e19b7bb5d55c5858a545f55928a5b51b8f2ab3d52cc86c561b14c32dc4178946b68dd02a86d75b6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 12:50:24.488866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:50:24.489230) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474140206.656539-171456378 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:50:24.573764) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 12:50:24.574134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 12:50:24.574485) ========= ========= Total master_lag: 0.46 =========