builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-016 starttime: 1490283289.4 results: success (0) revision: 439ee2e4fc4ed4a82102608639d9751e376d5536 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.399411) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.399881) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.400155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.415201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.415744) ========= 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=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-23 08:34:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-03-23 08:34:49 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110728 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.540488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.540828) ========= 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=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020211 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:49.590677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 08:34:49.590999) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 439ee2e4fc4ed4a82102608639d9751e376d5536 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 439ee2e4fc4ed4a82102608639d9751e376d5536 --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=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-23 08:34:49,879 truncating revision to first 12 chars 2017-03-23 08:34:49,879 Setting DEBUG logging. 2017-03-23 08:34:49,879 attempt 1/10 2017-03-23 08:34:49,879 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/439ee2e4fc4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 08:34:51,185 unpacking tar archive at: mozilla-central-439ee2e4fc4e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.592285 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 08:34:51.197196) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:51.197614) ========= script_repo_revision: 439ee2e4fc4ed4a82102608639d9751e376d5536 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:51.197995) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:51.198263) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:34:51.207914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-23 08:34:51.208268) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:34:51 INFO - MultiFileLogger online at 20170323 08:34:51 in /builds/slave/test 08:34:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:34:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:34:51 INFO - {'append_to_log': False, 08:34:51 INFO - 'base_work_dir': '/builds/slave/test', 08:34:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:34:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:34:51 INFO - 'branch': 'Firefox-Non-PGO', 08:34:51 INFO - 'buildbot_json_path': 'buildprops.json', 08:34:51 INFO - 'config_files': ('talos/linux_config.py',), 08:34:51 INFO - 'default_actions': ('clobber', 08:34:51 INFO - 'read-buildbot-config', 08:34:51 INFO - 'download-and-extract', 08:34:51 INFO - 'populate-webroot', 08:34:51 INFO - 'create-virtualenv', 08:34:51 INFO - 'install', 08:34:51 INFO - 'run-tests'), 08:34:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:34:51 INFO - 'download_minidump_stackwalk': True, 08:34:51 INFO - 'download_symbols': 'ondemand', 08:34:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:34:51 INFO - 'tooltool.py': '/tools/tooltool.py', 08:34:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:34:51 INFO - '/tools/misc-python/virtualenv.py')}, 08:34:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:34:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:34:51 INFO - 'gecko_profile': False, 08:34:51 INFO - 'gecko_profile_interval': 0, 08:34:51 INFO - 'installer_path': 'installer.exe', 08:34:51 INFO - 'log_level': 'info', 08:34:51 INFO - 'log_name': 'talos', 08:34:51 INFO - 'log_to_console': True, 08:34:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:34:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:34:51 INFO - 'opt_config_files': (), 08:34:51 INFO - 'pip_index': False, 08:34:51 INFO - 'suite': 'dromaeojs', 08:34:51 INFO - 'system_bits': '32', 08:34:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:34:51 INFO - 'title': 'talos-linux64-ix-016', 08:34:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:34:51 INFO - 'use_talos_json': True, 08:34:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:34:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:34:51 INFO - 'work_dir': 'build'} 08:34:51 INFO - [mozharness: 2017-03-23 15:34:51.593418Z] Running clobber step. 08:34:51 INFO - Running pre-action listener: _resource_record_pre_action 08:34:51 INFO - Running main action method: clobber 08:34:51 INFO - rmtree: /builds/slave/test/build 08:34:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:53 INFO - Running post-action listener: _resource_record_post_action 08:34:53 INFO - [mozharness: 2017-03-23 15:34:53.075090Z] Finished clobber step (success) 08:34:53 INFO - [mozharness: 2017-03-23 15:34:53.075188Z] Running read-buildbot-config step. 08:34:53 INFO - Running pre-action listener: _resource_record_pre_action 08:34:53 INFO - Running main action method: read_buildbot_config 08:34:53 INFO - Using buildbot properties: 08:34:53 INFO - { 08:34:53 INFO - "project": "", 08:34:53 INFO - "product": "firefox", 08:34:53 INFO - "who": "jyavenard@mozilla.com", 08:34:53 INFO - "installer_path": "public/build/target.tar.bz2", 08:34:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:34:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:34:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:34:53 INFO - "stage_platform": "linux64", 08:34:53 INFO - "basedir": "/builds/slave/test", 08:34:53 INFO - "buildnumber": 507, 08:34:53 INFO - "platform": "ubuntu64_hw", 08:34:53 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:34:53 INFO - "slavebuilddir": "test", 08:34:53 INFO - "taskId": "W1aq_oTARRywRKlEe7l6MQ", 08:34:53 INFO - "branch": "mozilla-central", 08:34:53 INFO - "script_repo_revision": "production", 08:34:53 INFO - "revision": "439ee2e4fc4ed4a82102608639d9751e376d5536", 08:34:53 INFO - "slavename": "talos-linux64-ix-016", 08:34:53 INFO - "repo_path": "mozilla-central" 08:34:53 INFO - } 08:34:53 INFO - Finding installer, test and symbols from parent task. 08:34:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1aq_oTARRywRKlEe7l6MQ'}, attempt #1 08:34:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1aq_oTARRywRKlEe7l6MQ'}, attempt #1 08:34:54 INFO - Task dependencies: JzD_l16pRq2tcIikmBN-2A 08:34:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A'}, attempt #1 08:34:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 08:34:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/test_packages.json 08:34:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.crashreporter-symbols.zip 08:34:54 INFO - Running post-action listener: _resource_record_post_action 08:34:54 INFO - [mozharness: 2017-03-23 15:34:54.496974Z] Finished read-buildbot-config step (success) 08:34:54 INFO - [mozharness: 2017-03-23 15:34:54.497190Z] Running download-and-extract step. 08:34:54 INFO - Running pre-action listener: _resource_record_pre_action 08:34:54 INFO - Running main action method: download_and_extract 08:34:54 INFO - mkdir: /builds/slave/test/build/tests 08:34:54 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')]} 08:34:54 INFO - trying https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.test_packages.json 08:34:54 INFO - Downloading https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:34:55 INFO - Downloaded 986 bytes. 08:34:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:34:55 INFO - Using the following test package requirements: 08:34:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:34:55 INFO - u'common': [u'target.common.tests.zip'], 08:34:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:34:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:34:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:34:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:34:55 INFO - u'mozbase': [u'target.common.tests.zip'], 08:34:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:34:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:34:55 INFO - u'web-platform': [u'target.common.tests.zip', 08:34:55 INFO - u'target.web-platform.tests.zip'], 08:34:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:34:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:34:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip 08:34:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:34:55 INFO - Fetch https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip into memory 08:34:56 INFO - Expected file size: 58125557 08:34:56 INFO - Obtained file size: 58125557 08:35:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:35:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip 08:35:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:35:06 INFO - Fetch https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.common.tests.zip into memory 08:35:07 INFO - Expected file size: 58125557 08:35:07 INFO - Obtained file size: 58125557 08:35:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.talos.tests.zip 08:35:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:35:17 INFO - Fetch https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.talos.tests.zip into memory 08:35:18 INFO - Expected file size: 14404171 08:35:18 INFO - Obtained file size: 14404171 08:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:18 INFO - trying https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 08:35:18 INFO - Downloading https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:35:20 INFO - Downloaded 62415565 bytes. 08:35:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 08:35:20 INFO - mkdir: /builds/slave/test/properties 08:35:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:35:20 INFO - Writing to file /builds/slave/test/properties/build_url 08:35:20 INFO - Contents: 08:35:20 INFO - build_url:https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 08:35:20 INFO - Running post-action listener: _resource_record_post_action 08:35:20 INFO - Running post-action listener: set_extra_try_arguments 08:35:20 INFO - [mozharness: 2017-03-23 15:35:20.150291Z] Finished download-and-extract step (success) 08:35:20 INFO - [mozharness: 2017-03-23 15:35:20.150408Z] Running populate-webroot step. 08:35:20 INFO - Running pre-action listener: _resource_record_pre_action 08:35:20 INFO - Running main action method: populate_webroot 08:35:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:20 INFO - '--tppagecycles', 08:35:20 INFO - '7'], 08:35:20 INFO - 'tests': ['tsvgm']}}, 08:35:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:35:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['damp', 'tps']}, 08:35:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'tests': ['damp', 'tps']}, 08:35:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['dromaeo_dom']}, 08:35:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['basic_compositor_video']}, 08:35:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:35:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['a11yr', 08:35:20 INFO - 'ts_paint', 08:35:20 INFO - 'tpaint', 08:35:20 INFO - 'sessionrestore', 08:35:20 INFO - 'sessionrestore_no_auto_restore', 08:35:20 INFO - 'tabpaint']}, 08:35:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['tsvgx', 08:35:20 INFO - 'tsvgr_opacity', 08:35:20 INFO - 'tart', 08:35:20 INFO - 'tscrollx', 08:35:20 INFO - 'cart', 08:35:20 INFO - 'tsvg_static']}, 08:35:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:20 INFO - 'tsvgr_opacity', 08:35:20 INFO - 'tart', 08:35:20 INFO - 'tscrollx', 08:35:20 INFO - 'cart', 08:35:20 INFO - 'tsvg_static']}, 08:35:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'talos_options': ['--disable-e10s'], 08:35:20 INFO - 'tests': ['tp5o']}, 08:35:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:35:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'talos_options': ['--disable-e10s', 08:35:20 INFO - '--xperf_path', 08:35:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:20 INFO - 'tests': ['tp5n']}, 08:35:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:35:20 INFO - 'talos_options': ['--xperf_path', 08:35:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:20 INFO - 'tests': ['tp5n']}}, 08:35:20 INFO - 'talos.zip': {'path': '', 08:35:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:20 INFO - Running post-action listener: _resource_record_post_action 08:35:20 INFO - [mozharness: 2017-03-23 15:35:20.165746Z] Finished populate-webroot step (success) 08:35:20 INFO - [mozharness: 2017-03-23 15:35:20.165833Z] Running create-virtualenv step. 08:35:20 INFO - Running pre-action listener: _resource_record_pre_action 08:35:20 INFO - Running main action method: create_virtualenv 08:35:20 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:35:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:20 INFO - Using real prefix '/usr' 08:35:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:22 INFO - Installing pip.................done. 08:35:22 INFO - Return code: 0 08:35:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:35:22 INFO - Reading from file tmpfile_stdout 08:35:22 INFO - Output received: 08:35:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:35:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:35:22 INFO - Using env: {'DISPLAY': ':0', 08:35:22 INFO - 'HOME': '/home/cltbld', 08:35:22 INFO - 'LANG': 'en_US.UTF-8', 08:35:22 INFO - 'LANGUAGE': 'en_US:en', 08:35:22 INFO - 'LOGNAME': 'cltbld', 08:35:22 INFO - 'MAIL': '/var/mail/cltbld', 08:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:22 INFO - 'MOZ_NO_REMOTE': '1', 08:35:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:22 INFO - 'NO_EM_RESTART': '1', 08:35:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:22 INFO - 'PWD': '/builds/slave/test', 08:35:22 INFO - 'SHELL': '/bin/bash', 08:35:22 INFO - 'SHLVL': '1', 08:35:22 INFO - 'TERM': 'linux', 08:35:22 INFO - 'TMOUT': '86400', 08:35:22 INFO - 'USER': 'cltbld', 08:35:22 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:22 INFO - '_': '/tools/buildbot/bin/python'} 08:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:35:23 INFO - Cleaning up... 08:35:23 INFO - Return code: 0 08:35:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:35:23 INFO - Using env: {'DISPLAY': ':0', 08:35:23 INFO - 'HOME': '/home/cltbld', 08:35:23 INFO - 'LANG': 'en_US.UTF-8', 08:35:23 INFO - 'LANGUAGE': 'en_US:en', 08:35:23 INFO - 'LOGNAME': 'cltbld', 08:35:23 INFO - 'MAIL': '/var/mail/cltbld', 08:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:23 INFO - 'MOZ_NO_REMOTE': '1', 08:35:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:23 INFO - 'NO_EM_RESTART': '1', 08:35:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:23 INFO - 'PWD': '/builds/slave/test', 08:35:23 INFO - 'SHELL': '/bin/bash', 08:35:23 INFO - 'SHLVL': '1', 08:35:23 INFO - 'TERM': 'linux', 08:35:23 INFO - 'TMOUT': '86400', 08:35:23 INFO - 'USER': 'cltbld', 08:35:23 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:23 INFO - '_': '/tools/buildbot/bin/python'} 08:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:23 INFO - Downloading/unpacking psutil>=3.1.1 08:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:25 INFO - Installing collected packages: psutil 08:35:25 INFO - Running setup.py install for psutil 08:35:25 INFO - building 'psutil._psutil_linux' extension 08:35:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:35:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:35:25 INFO - building 'psutil._psutil_posix' extension 08:35:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:35:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:35:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:26 INFO - Successfully installed psutil 08:35:26 INFO - Cleaning up... 08:35:26 INFO - Return code: 0 08:35:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:35:26 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')]} 08:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:26 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')]} 08:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:26 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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:26 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 08:35:26 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 08:35:26 INFO - Using env: {'DISPLAY': ':0', 08:35:26 INFO - 'HOME': '/home/cltbld', 08:35:26 INFO - 'LANG': 'en_US.UTF-8', 08:35:26 INFO - 'LANGUAGE': 'en_US:en', 08:35:26 INFO - 'LOGNAME': 'cltbld', 08:35:26 INFO - 'MAIL': '/var/mail/cltbld', 08:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:26 INFO - 'MOZ_NO_REMOTE': '1', 08:35:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:26 INFO - 'NO_EM_RESTART': '1', 08:35:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:26 INFO - 'PWD': '/builds/slave/test', 08:35:26 INFO - 'SHELL': '/bin/bash', 08:35:26 INFO - 'SHLVL': '1', 08:35:26 INFO - 'TERM': 'linux', 08:35:26 INFO - 'TMOUT': '86400', 08:35:26 INFO - 'USER': 'cltbld', 08:35:26 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:26 INFO - '_': '/tools/buildbot/bin/python'} 08:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:35:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:35:28 INFO - Installing collected packages: mozsystemmonitor 08:35:28 INFO - Running setup.py install for mozsystemmonitor 08:35:28 INFO - Successfully installed mozsystemmonitor 08:35:28 INFO - Cleaning up... 08:35:28 INFO - Return code: 0 08:35:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:35:28 INFO - Using env: {'DISPLAY': ':0', 08:35:28 INFO - 'HOME': '/home/cltbld', 08:35:28 INFO - 'LANG': 'en_US.UTF-8', 08:35:28 INFO - 'LANGUAGE': 'en_US:en', 08:35:28 INFO - 'LOGNAME': 'cltbld', 08:35:28 INFO - 'MAIL': '/var/mail/cltbld', 08:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:28 INFO - 'MOZ_NO_REMOTE': '1', 08:35:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:28 INFO - 'NO_EM_RESTART': '1', 08:35:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:28 INFO - 'PWD': '/builds/slave/test', 08:35:28 INFO - 'SHELL': '/bin/bash', 08:35:28 INFO - 'SHLVL': '1', 08:35:28 INFO - 'TERM': 'linux', 08:35:28 INFO - 'TMOUT': '86400', 08:35:28 INFO - 'USER': 'cltbld', 08:35:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:28 INFO - '_': '/tools/buildbot/bin/python'} 08:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:28 INFO - Downloading/unpacking jsonschema==2.5.1 08:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:35:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:35:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:35:30 INFO - warning: no files found matching '*.txt' 08:35:30 INFO - no previously-included directories found matching 'build' 08:35:30 INFO - no previously-included directories found matching 'dist' 08:35:30 INFO - no previously-included directories found matching '.git*' 08:35:30 INFO - Installing collected packages: jsonschema, functools32 08:35:31 INFO - Running setup.py install for functools32 08:35:31 INFO - warning: no files found matching '*.txt' 08:35:31 INFO - no previously-included directories found matching 'build' 08:35:31 INFO - no previously-included directories found matching 'dist' 08:35:31 INFO - no previously-included directories found matching '.git*' 08:35:31 INFO - Successfully installed jsonschema functools32 08:35:31 INFO - Cleaning up... 08:35:31 INFO - Return code: 0 08:35:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:35:31 INFO - Using env: {'DISPLAY': ':0', 08:35:31 INFO - 'HOME': '/home/cltbld', 08:35:31 INFO - 'LANG': 'en_US.UTF-8', 08:35:31 INFO - 'LANGUAGE': 'en_US:en', 08:35:31 INFO - 'LOGNAME': 'cltbld', 08:35:31 INFO - 'MAIL': '/var/mail/cltbld', 08:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:31 INFO - 'MOZ_NO_REMOTE': '1', 08:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:31 INFO - 'NO_EM_RESTART': '1', 08:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:31 INFO - 'PWD': '/builds/slave/test', 08:35:31 INFO - 'SHELL': '/bin/bash', 08:35:31 INFO - 'SHLVL': '1', 08:35:31 INFO - 'TERM': 'linux', 08:35:31 INFO - 'TMOUT': '86400', 08:35:31 INFO - 'USER': 'cltbld', 08:35:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:31 INFO - '_': '/tools/buildbot/bin/python'} 08:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:35:31 INFO - Cleaning up... 08:35:31 INFO - Return code: 0 08:35:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:35:31 INFO - Using env: {'DISPLAY': ':0', 08:35:31 INFO - 'HOME': '/home/cltbld', 08:35:31 INFO - 'LANG': 'en_US.UTF-8', 08:35:31 INFO - 'LANGUAGE': 'en_US:en', 08:35:31 INFO - 'LOGNAME': 'cltbld', 08:35:31 INFO - 'MAIL': '/var/mail/cltbld', 08:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:31 INFO - 'MOZ_NO_REMOTE': '1', 08:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:31 INFO - 'NO_EM_RESTART': '1', 08:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:31 INFO - 'PWD': '/builds/slave/test', 08:35:31 INFO - 'SHELL': '/bin/bash', 08:35:31 INFO - 'SHLVL': '1', 08:35:31 INFO - 'TERM': 'linux', 08:35:31 INFO - 'TMOUT': '86400', 08:35:31 INFO - 'USER': 'cltbld', 08:35:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:31 INFO - '_': '/tools/buildbot/bin/python'} 08:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:33 INFO - Downloading blobuploader-1.2.4.tar.gz 08:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:35:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:35:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:34 INFO - Downloading docopt-0.6.1.tar.gz 08:35:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:35:34 INFO - Installing collected packages: blobuploader, requests, docopt 08:35:34 INFO - Running setup.py install for blobuploader 08:35:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:35:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:35:34 INFO - Running setup.py install for requests 08:35:34 INFO - Running setup.py install for docopt 08:35:34 INFO - Successfully installed blobuploader requests docopt 08:35:34 INFO - Cleaning up... 08:35:35 INFO - Return code: 0 08:35:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:35 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')]} 08:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:35 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')]} 08:35:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:35 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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:35 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 08:35:35 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 08:35:35 INFO - Using env: {'DISPLAY': ':0', 08:35:35 INFO - 'HOME': '/home/cltbld', 08:35:35 INFO - 'LANG': 'en_US.UTF-8', 08:35:35 INFO - 'LANGUAGE': 'en_US:en', 08:35:35 INFO - 'LOGNAME': 'cltbld', 08:35:35 INFO - 'MAIL': '/var/mail/cltbld', 08:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:35 INFO - 'MOZ_NO_REMOTE': '1', 08:35:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:35 INFO - 'NO_EM_RESTART': '1', 08:35:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:35 INFO - 'PWD': '/builds/slave/test', 08:35:35 INFO - 'SHELL': '/bin/bash', 08:35:35 INFO - 'SHLVL': '1', 08:35:35 INFO - 'TERM': 'linux', 08:35:35 INFO - 'TMOUT': '86400', 08:35:35 INFO - 'USER': 'cltbld', 08:35:35 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:35 INFO - '_': '/tools/buildbot/bin/python'} 08:35:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:35 INFO - Running setup.py (path:/tmp/pip-V_19qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:35 INFO - Running setup.py (path:/tmp/pip-cw9dzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:35 INFO - Running setup.py (path:/tmp/pip-yjRDs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:35 INFO - Running setup.py (path:/tmp/pip-_4ix7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:35 INFO - Running setup.py (path:/tmp/pip-EJftV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:35 INFO - Running setup.py (path:/tmp/pip-nJGjzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:35 INFO - Running setup.py (path:/tmp/pip-xqi9fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:35 INFO - Running setup.py (path:/tmp/pip-VzhQYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:35 INFO - Running setup.py (path:/tmp/pip-Opivng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:35 INFO - Running setup.py (path:/tmp/pip-2gMICs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:35 INFO - Running setup.py (path:/tmp/pip-PIZlCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:36 INFO - Running setup.py (path:/tmp/pip-T_OXZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:36 INFO - Running setup.py (path:/tmp/pip-I8hur1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:36 INFO - Running setup.py (path:/tmp/pip-mlGyXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:36 INFO - Running setup.py (path:/tmp/pip-kdwpTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:36 INFO - Running setup.py (path:/tmp/pip-gYWUMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:36 INFO - Running setup.py (path:/tmp/pip-wTLwqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:35:36 INFO - Running setup.py install for manifestparser 08:35:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:35:36 INFO - Running setup.py install for mozcrash 08:35:36 INFO - Running setup.py install for mozdebug 08:35:36 INFO - Running setup.py install for mozdevice 08:35:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:35:36 INFO - Running setup.py install for mozfile 08:35:37 INFO - Running setup.py install for mozhttpd 08:35:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozinfo 08:35:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozInstall 08:35:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozleak 08:35:37 INFO - Running setup.py install for mozlog 08:35:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for moznetwork 08:35:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozprocess 08:35:37 INFO - Running setup.py install for mozprofile 08:35:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozrunner 08:35:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:35:37 INFO - Running setup.py install for mozscreenshot 08:35:38 INFO - Running setup.py install for moztest 08:35:38 INFO - Running setup.py install for mozversion 08:35:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:35:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:35:38 INFO - Cleaning up... 08:35:38 INFO - Return code: 0 08:35:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:38 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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:38 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 08:35:38 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 08:35:38 INFO - Using env: {'DISPLAY': ':0', 08:35:38 INFO - 'HOME': '/home/cltbld', 08:35:38 INFO - 'LANG': 'en_US.UTF-8', 08:35:38 INFO - 'LANGUAGE': 'en_US:en', 08:35:38 INFO - 'LOGNAME': 'cltbld', 08:35:38 INFO - 'MAIL': '/var/mail/cltbld', 08:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:38 INFO - 'MOZ_NO_REMOTE': '1', 08:35:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:38 INFO - 'NO_EM_RESTART': '1', 08:35:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:38 INFO - 'PWD': '/builds/slave/test', 08:35:38 INFO - 'SHELL': '/bin/bash', 08:35:38 INFO - 'SHLVL': '1', 08:35:38 INFO - 'TERM': 'linux', 08:35:38 INFO - 'TMOUT': '86400', 08:35:38 INFO - 'USER': 'cltbld', 08:35:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:38 INFO - '_': '/tools/buildbot/bin/python'} 08:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:38 INFO - Running setup.py (path:/tmp/pip-NhtFFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:38 INFO - Running setup.py (path:/tmp/pip-XbLmi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:38 INFO - Running setup.py (path:/tmp/pip-640_YA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:38 INFO - Running setup.py (path:/tmp/pip-MPgE2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:38 INFO - Running setup.py (path:/tmp/pip-P2HR1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:38 INFO - Running setup.py (path:/tmp/pip-rR_M1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:38 INFO - Running setup.py (path:/tmp/pip-9Zl9RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:39 INFO - Running setup.py (path:/tmp/pip-ccKo4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:39 INFO - Running setup.py (path:/tmp/pip-vatzwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:39 INFO - Running setup.py (path:/tmp/pip-71eUFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:39 INFO - Running setup.py (path:/tmp/pip-ht1QWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:39 INFO - Running setup.py (path:/tmp/pip-6m1NeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:39 INFO - Running setup.py (path:/tmp/pip-dnaeCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:39 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:39 INFO - Running setup.py (path:/tmp/pip-IEG1uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:39 INFO - Running setup.py (path:/tmp/pip-9fEksi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:39 INFO - Running setup.py (path:/tmp/pip-SpJKPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:39 INFO - Running setup.py (path:/tmp/pip-CGIOky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:35:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:41 INFO - Downloading blessings-1.6.tar.gz 08:35:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:41 INFO - Installing collected packages: blessings 08:35:41 INFO - Running setup.py install for blessings 08:35:41 INFO - Successfully installed blessings 08:35:41 INFO - Cleaning up... 08:35:42 INFO - Return code: 0 08:35:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:42 INFO - Reading from file tmpfile_stdout 08:35:42 INFO - Current package versions: 08:35:42 INFO - argparse == 1.2.1 08:35:42 INFO - blessings == 1.6 08:35:42 INFO - blobuploader == 1.2.4 08:35:42 INFO - docopt == 0.6.1 08:35:42 INFO - functools32 == 3.2.3-2 08:35:42 INFO - jsonschema == 2.5.1 08:35:42 INFO - manifestparser == 1.1 08:35:42 INFO - mozInstall == 1.12 08:35:42 INFO - mozcrash == 1.0 08:35:42 INFO - mozdebug == 0.1 08:35:42 INFO - mozdevice == 0.49 08:35:42 INFO - mozfile == 1.2 08:35:42 INFO - mozhttpd == 0.7 08:35:42 INFO - mozinfo == 0.9 08:35:42 INFO - mozleak == 0.1 08:35:42 INFO - mozlog == 3.4 08:35:42 INFO - moznetwork == 0.27 08:35:42 INFO - mozprocess == 0.25 08:35:42 INFO - mozprofile == 0.28 08:35:42 INFO - mozrunner == 6.13 08:35:42 INFO - mozscreenshot == 0.1 08:35:42 INFO - mozsystemmonitor == 0.3 08:35:42 INFO - moztest == 0.8 08:35:42 INFO - mozversion == 1.4 08:35:42 INFO - psutil == 3.1.1 08:35:42 INFO - requests == 1.2.3 08:35:42 INFO - wsgiref == 0.1.2 08:35:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:42 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')]} 08:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:42 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')]} 08:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:42 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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:42 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 08:35:42 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 08:35:42 INFO - Using env: {'DISPLAY': ':0', 08:35:42 INFO - 'HOME': '/home/cltbld', 08:35:42 INFO - 'LANG': 'en_US.UTF-8', 08:35:42 INFO - 'LANGUAGE': 'en_US:en', 08:35:42 INFO - 'LOGNAME': 'cltbld', 08:35:42 INFO - 'MAIL': '/var/mail/cltbld', 08:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:42 INFO - 'MOZ_NO_REMOTE': '1', 08:35:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:42 INFO - 'NO_EM_RESTART': '1', 08:35:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:42 INFO - 'PWD': '/builds/slave/test', 08:35:42 INFO - 'SHELL': '/bin/bash', 08:35:42 INFO - 'SHLVL': '1', 08:35:42 INFO - 'TERM': 'linux', 08:35:42 INFO - 'TMOUT': '86400', 08:35:42 INFO - 'USER': 'cltbld', 08:35:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:42 INFO - '_': '/tools/buildbot/bin/python'} 08:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 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)) 08:35:42 INFO - Cleaning up... 08:35:42 INFO - Return code: 0 08:35:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:35:42 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')]} 08:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:42 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')]} 08:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:42 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 0xb451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, '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': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', '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 08:35:42 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 08:35:42 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 08:35:42 INFO - Using env: {'DISPLAY': ':0', 08:35:42 INFO - 'HOME': '/home/cltbld', 08:35:42 INFO - 'LANG': 'en_US.UTF-8', 08:35:42 INFO - 'LANGUAGE': 'en_US:en', 08:35:42 INFO - 'LOGNAME': 'cltbld', 08:35:42 INFO - 'MAIL': '/var/mail/cltbld', 08:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:42 INFO - 'MOZ_NO_REMOTE': '1', 08:35:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:42 INFO - 'NO_EM_RESTART': '1', 08:35:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:42 INFO - 'PWD': '/builds/slave/test', 08:35:42 INFO - 'SHELL': '/bin/bash', 08:35:42 INFO - 'SHLVL': '1', 08:35:42 INFO - 'TERM': 'linux', 08:35:42 INFO - 'TMOUT': '86400', 08:35:42 INFO - 'USER': 'cltbld', 08:35:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:42 INFO - '_': '/tools/buildbot/bin/python'} 08:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:35:42 INFO - Cleaning up... 08:35:42 INFO - Return code: 0 08:35:42 INFO - Running post-action listener: _resource_record_post_action 08:35:42 INFO - Running post-action listener: _start_resource_monitoring 08:35:42 INFO - Starting resource monitoring. 08:35:42 INFO - [mozharness: 2017-03-23 15:35:42.762592Z] Finished create-virtualenv step (success) 08:35:42 INFO - [mozharness: 2017-03-23 15:35:42.763063Z] Running install step. 08:35:42 INFO - Running pre-action listener: _resource_record_pre_action 08:35:42 INFO - Running main action method: install 08:35:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:42 INFO - Reading from file tmpfile_stdout 08:35:42 INFO - Detecting whether we're running mozinstall >=1.0... 08:35:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:35:43 INFO - Reading from file tmpfile_stdout 08:35:43 INFO - Output received: 08:35:43 INFO - Usage: mozinstall [options] installer 08:35:43 INFO - Options: 08:35:43 INFO - -h, --help show this help message and exit 08:35:43 INFO - -d DEST, --destination=DEST 08:35:43 INFO - Directory to install application into. [default: 08:35:43 INFO - "/builds/slave/test"] 08:35:43 INFO - --app=APP Application being installed. [default: firefox] 08:35:43 INFO - mkdir: /builds/slave/test/build/application 08:35:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:35:58 INFO - Reading from file tmpfile_stdout 08:35:58 INFO - Output received: 08:35:58 INFO - /builds/slave/test/build/application/firefox/firefox 08:35:58 INFO - Running post-action listener: _resource_record_post_action 08:35:58 INFO - [mozharness: 2017-03-23 15:35:58.717194Z] Finished install step (success) 08:35:58 INFO - [mozharness: 2017-03-23 15:35:58.717464Z] Running run-tests step. 08:35:58 INFO - Running pre-action listener: _resource_record_pre_action 08:35:58 INFO - Running main action method: run_tests 08:35:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:58 INFO - Python 2.7.3 08:35:58 INFO - Return code: 0 08:35:58 INFO - grabbing minidump binary from tooltool 08:35: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')]} 08:35:58 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 0xde0290>, '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 0xde0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xde1040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35:58 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 08:35:58 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 08:35:58 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 08:35:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:58 INFO - Return code: 0 08:35:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:35:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:58 INFO - ENV: RUST_BACKTRACE is now 1 08:35:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:35:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 08:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 08:35:58 INFO - Using env: {'DISPLAY': ':0', 08:35:58 INFO - 'HOME': '/home/cltbld', 08:35:58 INFO - 'LANG': 'en_US.UTF-8', 08:35:58 INFO - 'LANGUAGE': 'en_US:en', 08:35:58 INFO - 'LOGNAME': 'cltbld', 08:35:58 INFO - 'MAIL': '/var/mail/cltbld', 08:35:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:35:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:58 INFO - 'MOZ_NO_REMOTE': '1', 08:35:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:35:58 INFO - 'NO_EM_RESTART': '1', 08:35:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:35:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:58 INFO - 'PWD': '/builds/slave/test', 08:35:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:58 INFO - 'RUST_BACKTRACE': '1', 08:35:58 INFO - 'SHELL': '/bin/bash', 08:35:58 INFO - 'SHLVL': '1', 08:35:58 INFO - 'TERM': 'linux', 08:35:58 INFO - 'TMOUT': '86400', 08:35:58 INFO - 'USER': 'cltbld', 08:35:58 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1490283288.913801-220132694', 08:35:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:58 INFO - '_': '/tools/buildbot/bin/python'} 08:35:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 08:35:59 INFO - mozversion application_buildid: 20170323150529 08:35:59 INFO - mozversion application_changeset: 439ee2e4fc4ed4a82102608639d9751e376d5536 08:35:59 INFO - mozversion application_display_name: Nightly 08:35:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:59 INFO - mozversion application_name: Firefox 08:35:59 INFO - mozversion application_remotingname: firefox 08:35:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:35:59 INFO - mozversion application_vendor: Mozilla 08:35:59 INFO - mozversion application_version: 55.0a1 08:35:59 INFO - mozversion platform_buildid: 20170323150529 08:35:59 INFO - mozversion platform_changeset: 439ee2e4fc4ed4a82102608639d9751e376d5536 08:35:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:35:59 INFO - mozversion platform_version: 55.0a1 08:35:59 INFO - using testdate: 1490283359 08:35:59 INFO - actual date: 1490283359 08:35:59 INFO - starting webserver on 'localhost:38734' 08:35:59 INFO - SUITE-START | Running 2 tests 08:35:59 INFO - TEST-START | dromaeo_css 08:35:59 INFO - operating with platform_type : linux_ 08:35:59 INFO - Initialising browser for dromaeo_css test... 08:35:59 INFO - TEST-INFO | started process 18994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnVegPE/profile http://localhost:38734/getInfo.html) 08:36:03 INFO - PID 18994 | __metrics Screen width/height:1600/1200 08:36:03 INFO - PID 18994 | colorDepth:24 08:36:03 INFO - PID 18994 | Browser inner width/height: 1024/697 08:36:03 INFO - PID 18994 | __metrics 08:36:04 INFO - PID 18994 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:36:04 INFO - PID 18994 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:36:05 INFO - TEST-INFO | 18994: exit 0 08:36:05 INFO - Browser initialized. 08:36:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:36:05 INFO - TEST-INFO | started process 19085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnVegPE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:17 INFO - PID 19085 | RSS: Main: 149475328 08:36:17 INFO - PID 19085 | 08:39:22 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38734/tests/dromaeo/cssquery-ext.html) 08:39:25 INFO - PID 19085 | RSS: Main: 395788288 08:39:25 INFO - PID 19085 | 08:41:07 INFO - PID 19085 | ************************* 08:41:07 INFO - PID 19085 | A coding exception was thrown and uncaught in a Task. 08:41:07 INFO - PID 19085 | 08:41:07 ERROR - PID 19085 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:41:07 INFO - PID 19085 | Full stack: 08:41:07 INFO - PID 19085 | ************************* 08:42:28 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-ext.html (next: http://localhost:38734/tests/dromaeo/cssquery-jquery.html) 08:42:28 INFO - PID 19085 | RSS: Main: 235347968 08:42:28 INFO - PID 19085 | 08:45:31 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38734/tests/dromaeo/cssquery-mootools.html) 08:45:31 INFO - PID 19085 | RSS: Main: 232796160 08:45:31 INFO - PID 19085 | 08:48:33 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38734/tests/dromaeo/cssquery-prototype.html) 08:48:34 INFO - PID 19085 | RSS: Main: 221323264 08:48:34 INFO - PID 19085 | 08:51:37 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38734/tests/dromaeo/cssquery-yui.html) 08:51:38 INFO - PID 19085 | RSS: Main: 188272640 08:51:38 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | Cycle 1(1): loaded http://localhost:38734/tests/dromaeo/cssquery-yui.html (next: http://localhost:38734/tests/dromaeo/cssquery-dojo.html) 08:54:41 INFO - PID 19085 | RSS: Main: 279949312 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | __start_tp_report 08:54:41 INFO - PID 19085 | _x_x_mozilla_page_load 08:54:41 INFO - PID 19085 | _x_x_mozilla_page_load_details 08:54:41 INFO - PID 19085 | |i|pagename|runs| 08:54:41 INFO - PID 19085 | |0;dojo.html;2638.393169571;2652.442986972736;2669.159214847323;2673.9598906016413;2680.785537157027;4929.162042452783;5039.6976181429045;5041.571466425354;5082.034413461444;5100.056489549436;5113.284140220372;5130.794768209276;5167.7932882684745;5178.948210517894;5210.557102646279;4590.816367345302;4616.2613981762925;4636.374089497908;4717.386739723831;4769.165396055694;4618.1687296286655;4674.462436819776;4715.976420117895;4718.174319494092;4734.124187025412;4542.091581683677;4589.33454649074;4595.448546174447;4622.86131416058;4665.463471700764;4650.209464391028;4650.372199753024;4663.743494107823;4710.505396933341;4726.763661816895;4575.428071491064;4622.745748983804;4637.976810115962;4656.511066338019;4696.671233013691;4997.375328083989;5048.242763585492;5057.115004874169;5084.51697088776;5105.463926287724;5522.723863806794;5528.530074943635;5562.193481945135;5569.610127291091;5579.497845193952;5581.916271255894;5588.161775733673;5610.971945140289;5611.13027480737;5616.129499927518;5065.189569668835;5123.436421993578;5164.096283150471;5198.2202669599865;5249.632525723239;5553.111502159635;5595.916061259084;5613.270274864241;5681.289838770153;5689.431056894277;5323.254744335796;5336.199570064521;5405.621606487587;5473.75368108436;5491.780328786884;5535.059039963216;5562.916556251659;5582.49757521825;5582.692951887685;5587.106063029895;5540.667559946416;5546.667199968015;5549.500544950893;5552.139418390156;5554.972225138848;5374.355077390738;5427.240186373911;5437.891242175135;5496.367917689437;5551.916721249185;4599.8390056348535;4602.470715867651;4606.124836281096;4638.536146385334;4652.55800698933;3946.2699400610404;3950.328444164499;3964.1278918637854;3984.2230765001127;3991.221711766236;4658.394408726844;4721;4743.051389722069;4771.997880445146;4825.372701548847;5585.385607583163;5589.161625756088;5591.41290164535;5641.746121424546;5644.915326270109;4739.810407583658;4767.904641907143;4778.904421911542;4780.163471392492;4824.179889418807;4763.095011947719;4927.187014142626;4937.753112344369;4944.728039957765;4979.427365852974;5030.421501527371;5068.467810879952;5130.97434512825;5183.222516622537;5193.246979188072;5596.664200147841;5598.384177740364;5602.0196465619165;5603.467670571289;5638.830835074954;5175.534201921694;5179.637425380338;5181.766820493011;5183.092958732283;5241.318628578413;5287.12762394216;5287.471252874681;5333.7866485340155;5344.35867695879;5492.533134683436;5429.891402172014;5460.34475862899;5461.208124821799;5464.098423760035;5470.781168753205;5247;5304.7082410466255;5308.787648494017;5311.389190243017;5319.494648008433;5281;5287.048331300249;5296.3379577552805;5307.01820163271;5369.677819330853;5286.36563612369;5287.101192797157;5296.258523806747;5298.629095963245;5299.5230429261555;5397.082495975772;5404.5406140476925;5408.4321146280645;5414.242006119225;5415.59383046281;5175.9741201293755;5212.244224587489;5213.061648903234;5221.817236396708;5265.341832270966 08:54:41 INFO - PID 19085 | |1;ext.html;567.860874085849;582.8076734677556;1040.4745603470246;1055.6358056470524;1055.7202341379536;30771.38457230857;31574.526382104286;31684.841575792118;31743.206419839516;31792;33241;33619;33643;33673;33738.325233495314;5490.176473528963;7309.232530584291;7622.656980435866;7625.694972201105;7627.466077374586;5638.746256418451;7057.364837164654;7200.35196832285;7203.315685009942;7206.315400036988;7274.672639731225;7334.339909408152;7335.743248986287;7339;7356.154042285126;5538.861528461781;7365.3002964718535;7387.7783666490095;7398.112226532797;7400.185979542246;5588.133839254922;7168.964155179243;7292.19785823559;7298.087739032621;7309.232530584265;33962.6603733962;34487.82756086203;34519.30961380783;34605;34663.65336346655;2510.297116807297;2521.8865151068244;2532.354249666323;2556.5398228318895;2558.398776287571;22824.201152959733;22864;23068.653970190462;23144.42138946523;23363.182288619988;2631.355317947096;2634.868256587163;2640.2079376187066;2642.365832200258;2662.773664238542;154285;154346;154554;154778;154848;30827;31461.685383145876;41717.58282417198;42006.579934200876;42013.15973680509;38848;39378.21243575113;39450.80274598609;39503.209935801126;39569.40645890314;11890.40547972598;11998.700032499257;12018.158728888893;12018.33899135556;12021.398930053638;9465.574049167804;9519.619215231312;9522.190613797904;9527.475988820543;9775.120239178508;12619.179753316004;12676.683082923;12687.984961203081;12723.491060357483;12828.80756788649;5140.100482415637;5196.168613021974;5264.6838290427395;5297.198952356217;5312.867178320495;845.2815107158866;857.5326447086228;888.1429420609143;891.7503099132254;912.4023764434306;18572.040781749878;19506.80493195078;20802.895985519976;20819.1672333108;20908.58182836335;906.0939060939061;957.7557722780647;966.1256562810527;976.7655762616921;987.1313244344931;10592;10686.358818470917;10923.235373523778;10929.180311476512;11000.724981875517;185498;185510;185609;185799;186114;38333.42499862504;38365.616343836766;38549.229015419536;38576.42135367972;38578;1172.3259126002583;1229.280870690638;1243.3534562027514;1244.2596654990266;1252.8809763072493;18817.90591047036;19041.714374284675;19601.215951361784;19626.313079042164;20194.89902550478;30674.693253067184;30706;30754.846225768728;30768;30769.076927692204;218521;218713;218735;218921;218933;20471.181152753725;20881.582368352243;25841.095561655253;26297.474050519264;29134.23224793726;31062;31251;31657;31747.20631984219;31992;25344;26177.214683559523;26647.86676066685;26697.7330226703;27052.45895082048;25188.496230075663;25636.871815640803;25877.223683289532;26224.21327360182;27023.324416889736;28308.150755477367;28398.43203135967;28418.289542761595;28514.429711406072;28622;25695;25753.356166095997;25907.740922590532;25949.481010380063;26167.476650467266;19899.403017909484;21398.78601213968;21451.927403630067;21475.892620536797;21518.462038449165 08:54:41 INFO - PID 19085 | |2;jquery.html;405.77276725034;408.55671596317995;426.1264407963675;428.7963217471701;435.91717573661003;16778.916105419485;17328.913355433237;17409.65180696385;17437.999999999985;18443.262269509203;18406;18515.722264166016;18654.626907461876;19163.317135601028;19292.3247686331;5638.859028524279;5689.260396148495;5770.653760774346;5819.7963071292515;5883.852903677421;5706.686132262724;5803.738831752581;5808.099744539603;5809.157672137538;5809.186713860063;5522.723863806814;5680.091185410335;5714.171445140452;5715.971125197463;5765.1928729977835;5683.772649094052;5725.541956643458;5733.88532229353;5736.856578585569;5738.770449182028;4948.183549714328;5052.368453943257;5083.669561478492;5090.847274581732;5091.338126043628;22527.436814079614;22672.54654906909;22681.54636907269;22855.20006799754;22967.425814354607;1539.3688587678998;1561.523735260745;1567.7648352747065;1583.754518049704;1602.2789744614854;13396.330183490787;14048.367823447968;14131.993193465387;14227.644308892257;14608.342624581814;1737.8870373425814;1750.1774166141893;1770.8848924819845;1773.379317238969;1775.458485162025;85597.57201214017;87560.3085799421;87773;89230.553847231;89890;10741.355518668905;10949.890501095046;10988.450577471094;11075.11399088071;11166.60916867922;15622.29699663518;15631.3747450103;15642.139682317582;15664.138472384127;15672.764908526382;7406.148292946273;7612.7716168515035;7642.121156067013;7762.611869406507;7783.688652453839;6873.518853680194;6976.441884649216;7130.92869071313;7136.536125151849;7176.102987126609;6782.896347709663;6791.422729068085;6985.3713165815325;7025.367716905503;7027.383701748524;1916;1924.9422517324504;1939.9030048497518;1950.3661310074283;2150.4838838678606;830.464350493928;858.4849090545623;865.4807115730637;867.2411639815161;878.6178012564554;16343.509694709177;16639.584010399594;16767.832321676626;17280.13599320054;17442.215100320514;856.8029353248787;857.498363457372;861.8233262181363;871.6067643832433;873.9730816290833;9239;9378.390404623678;9480.288978326653;9596.376235544667;9629.229661627052;416986;428587;430764;434509;445025;55100.72449637806;78121.60939195381;82846.58576707199;83392.58303708442;85558;1032.4734385463469;1037.7094413564214;1077.6605369308643;1080.308602494404;1080.713610893114;16565.58586035359;16901.746473802657;17290.740638890176;17298.135093245037;17360.305587776347;29044.56433153547;30237.395252095273;37810.62189378071;39053.609463905;39199;49577;49773;49892.25161622651;49987;50929;7742;7790.649420776192;7791.883121753292;7879.409044321585;8179;12496.31270280107;12703.745925081632;12712.936435317764;12927.224366538037;12938.288394138037;9745.366551174151;9848.212143028397;9989.200863931013;10020.498975051072;10240.487975601041;8695.260902823078;8999.82000359976;9340.766480838034;9351.906480935104;9626.47054412014;10948.178886583379;11205.327680339205;11227.438628068727;11257.324560526395;11552;8070.87893681607;8308.169183081643;8409.831803363775;8516.74449766508;8520.446170998865;11058.11535077208;11414.315141091562;11446.198766086294;11506.654800356002;11691.181617287048 08:54:41 INFO - PID 19085 | |3;mootools.html;1229.8339724137243;1297.8896793772528;1315.6447759105042;1318.0444177970978;1320.9933950330253;9388.953055234722;9758.756031099227;9770.560324785383;9797.99999999999;9800.06899344563;9549.140577348036;9736.561854716538;9946.303758736873;10026.649067282646;10038.799224015533;2156.6225910465646;2190.791874771894;2190.9342719718434;2196.4728465168323;2197.4726065744267;1784.723367877981;2093.5289559849066;2096.350131459245;2122.9363119106374;2174.3042226487532;1751.527087686324;2091.8640288381207;2149.6453085240914;2153.3001774423296;2155.2887547109417;2067.5968186203695;2089.3105275259127;2100.6953991671276;2102.915883364671;2108.114591871418;2028.4421784009369;2037.1443993522607;2042.4383294593956;2048.354768247997;2049.231538173185;9672.95163524178;9884.752881177956;9893.653722119763;9898.109170174646;9900.059494348025;5109.693418394908;5288.497592728723;5292.047431462335;5300.01949639318;5300.761465734051;3051.816890986548;3178.3802158579088;3196.6963138502038;3281.917952051194;3306.9834650826833;980.6371642492323;1032.4203967929082;1046.1055797293357;1064.1912146768434;1073.2326386633517;14744.336504857307;14883.734882534889;14938.253087345589;14939.253037348199;14940.402383904631;4596.701214421052;4751.928721069186;4808.374911261583;4822.517748225219;4830.372051633265;10655.627053053106;10670.62652807148;10686.0916822071;10689.30519516229;10694.358338499715;5078.08594453002;5082.491750824888;5084.6440749147205;5087.669301495391;5092.465291144451;4651.906961860744;4691.272852707836;4866.8783280417565;4922.384701912261;4933.235348520985;3643.5809881863925;3796.5064541609413;3813.065798879237;3859.4789703390243;3900.258950799339;1059.6291298045621;1075.3171735947728;1084.5770149641646;1092.431935393591;1097.846301517788;553.6822362776566;615.5106690181227;672.1530871102349;686.7562015484531;689.1798759476208;5499.697516636624;5578.888422231533;5598.300212473441;5626.43735626434;5739.85650358736;558.8714595643025;562.8986782379129;563.7660370946094;570.4124751505956;641.6214433484266;3268.803871767654;3373.6120346160014;3453.1194545391268;3599.9560127563236;3629.3648611493413;12430.192037517532;13149;13162.670933226555;13200.537981170613;13205.33973301331;12665.810012849815;13069.869301307055;13070.215787052617;13088.803667944989;13102.279374634487;8980.685676001309;9184.173424391836;9199.126083022236;9209.079092090737;9217.493037882981;5501.614886957875;5539.282822325092;5547.11246200606;5616.382197958303;5741.3971532988435;11729;11733.061355091402;11784.646460606196;11820.645380638594;11915.52337906474;10936.398498082364;11190.216684831983;11313.943430282796;11321.094312455149;11343.716407089889;8335;8400.327973761961;8551.35864810154;8571.142885711379;8577.699780507652;9119.863202051842;9128.634854605742;9223.631054758003;9224.40041397307;9235.030321816113;7569.848603028019;7591.99944468117;7739.613019348897;7857.371410287049;7900.604969751605;7218.097737782778;7359.4480413968095;7362.7054917804835;7399.186089530255;7399.815004624712;7252.927470725225;7387.963060184665;7389.926100738924;7398.704051838082;7408.222136675571;5224;5267.73661316925;5311.707856067954;5353.384360798558;5538.97230513861;5353.866153346042;5359.276497672764;5425.972870135624;5472.808451704172;5493.395726470165 08:54:41 INFO - PID 19085 | |4;prototype.html;375.62249938811493;395.353596869119;397.1897329447925;398.76871414579546;399.94600728901605;5339.786408543659;5406.432324605918;5444.047291723942;5445.48267914548;5450.4549545045475;5118.436971933093;5284.603654725901;5301.065111234391;5330.733463326827;5341.30563026806;4120.484939382577;4122.97938510307;4130.256553820311;4131.834726610932;4142.212979533879;4130.8967275818195;4153.293940030202;4153.833846646131;4157.293260145782;4159.979200103995;3922.666573341269;4092.7339722918064;4109.938350924724;4114.991827002388;4132.380142978547;4080.979595102035;4085.754854708727;4094.6314831664995;4107.240160570304;4107.322291821846;3612.187257866985;3613.8012409317475;3618.113562177257;3624.3294990426857;3641.308151451216;4964.205727083686;5207.432908457718;5219.086659834514;5230.895382092375;5244.895102097975;4694.812207511695;4708.717476951394;4709.693869898446;4721.409823772028;4738.549837765441;5630;5633.464820842013;5636.408177141382;5641.90961552875;5648.717564121777;3803.315403227418;3953.3081710700512;3964.4053391991433;3965.7422267552515;3966.8809935701975;5615.522680572116;5737.340205876378;5828.745016138243;5911.940880591182;5954.97022514889;4050.736702114353;4053.2906741320153;4089.5705950875326;4090.5909409058854;4098.590140985878;4151.813168407369;4152.418661387408;4157.230912281238;4157.584241575818;4157.625813676784;4379.715318504351;4475.172093162775;4715.481297057321;4765.642576806753;4772.0694464579765;5586.91619625706;5597.257343901459;5613.915791263134;5620.409856965041;5631.7747290108755;1415.2074838090703;1433.9498117565836;1437.9209143497208;1439.985600143985;1441.2649548730014;940.5156344482597;962.1052421248166;962.3119469579144;972.2707969023232;974.7222041718218;4932.347663532757;4942.110420124341;4948.713334533048;4956.132676781549;4964.205727083649;5791.971040144772;5803.6807975560905;5829.154772557956;5838.591298609142;5849.122631605208;4824.228123500223;4895.877603059952;4902.7058376497525;4960.454350021495;4962.082014827269;3055.266735983348;3064.341166649181;3069.4014667139695;3080.1529579365406;3096.5664806927048;5378.408375078739;5388.461153884658;5393.056215162331;5407.486288802557;5420.105682562412;5796.6096620141625;5860.619059761102;5888.558358123072;5916.7337469813965;5919.881602367928;5349.759260833272;5372.059889519397;5372.731363431891;5375.3012108425655;5399.78400863961;5651.971740141273;5705.714714264187;5770.13447982806;5784.132380143013;5802.738876750472;5640.097584386478;5651.8304450866535;5682.374938756816;5715.342735585295;5722.198892155185;5129.358830146231;5226.242194881797;5312.150055991023;5388.353397592314;5391.379991300893;4046.67626589878;4056.6957478189843;4057.594240575919;4060.4112403700706;4082.9795851020554;5761.913571296518;5798.217240672624;5810.448007439287;5822.359540450462;5847;5441.156620723794;5614.2701448811395;5636.788090560549;5646.774129034792;5656.6888821115235;5575.77696892136;5618.831435056954;5628.690422026828;5636.267285252891;5649.830505084854;5380.838574842761;5482.643628164156;5527.738634205838;5564.4991950724625;5575;5623.156526520891;5639.407862174413;5640.943590564042;5659;5695.117256825199;5511.614187007031;5519.751611177587;5525.917111243254;5541.141123125761;5548.389677135431 08:54:41 INFO - PID 19085 | |5;yui.html;688.7761477519807;691.875940861555;695.1935754524751;699.7865650976453;709.8367375503631;11007.944960275197;11770.411479426026;11786.233894796844;13558.728825423486;14151.929240353784;14645.04807187532;14817.481388151416;14838.406463741438;14849.406023759037;15371.769423458629;814.352589691194;827.065416079829;835.1481488881337;838.6771093129158;838.9874151887726;838.6268110690745;850.2093053460279;850.9149085091473;853.1681610429844;854.700854700856;883.5405589093667;884.1644645809712;884.3765145572356;884.4516399832113;892.3753372639146;885.0927799006038;887.951162686052;890.2877697841701;890.6036813617943;891.9598618062138;651.9967400163016;659.5020759326726;659.7558903205796;659.8548319369731;660.1582981698325;13066.0200484964;13148.145370550883;13333;13413.798793018113;13692.452301907912;12459.875401245961;12615.432305546181;12728.299943503103;13050;13441.126326788728;9806.41963068534;9824;10082.798344033079;10099.69700908974;10161.33951293171;2661.308059904419;2675.357914100621;2682.825616334932;2776.9583456248174;2781.5132351838347;66175.33824661787;66345.33654663488;67103.32896671069;67210.66394668045;67944.6602766983;10100.598623910124;10282.948585257176;10288.948555257175;10365;10438.68683939483;20867.1653133876;20867.791322086887;20868.895655521625;20937.37187884366;20963.895180524305;5904.7342869570975;6103.908441373383;6169.2288463942;6205.751769929152;6253.187085678833;3278.60656721195;3355.127666806648;3387.4749413840896;3389.220479289728;3480.3213373391945;3460.169559305799;3505.4566542186003;3529.9117522061642;3550.55618047744;3649.489071529988;681.6455443169524;688.6384648059709;690.7858563845224;707.9575225486487;709.018010056076;503.1597232621531;540.0980362794144;543.4646872830259;548.4241546376289;552.2654869024188;7830.8433831323055;8168.795780105426;8428;8549.487030778173;8637.827243455222;482.8551434569615;487.7780609822522;504.90154419888506;507.6066048812126;525.9368875734937;3632.9636703633155;3732.9813350933073;3782.7730336179916;3788.5453745550158;3964.702647301464;59087;60497;61431.07853382203;63721.68139159275;63783.36216637867;53296.73351633217;53444;53569.46430535723;54387;55242.44757552454;47433;47755;47946.280805787945;48183.75908120437;49296.75351623219;7608.885866711893;7631.618419078913;7782.338501227233;7842.529448233125;7940.92059079425;26080;26236.475270494866;26390.73609263883;26555.601665975413;26880.865595671898;32173.839130804197;32228;32274.515882262254;32281.838590806896;32889;9805.264605154785;9853.852192217266;9948.104670579683;10009.74975625586;10118.08937195656;9801.264905132286;9819.5090245486;9973.401595904268;10217.284790064625;10422.635207767691;10096.495175241062;10436.739081523023;10593.311434756715;10656.840147397641;10808.351498910091;9642.903570964481;10192.898071019492;10280.485975701335;10290.639827605997;10507;9708.85436718464;10209.438480883622;10238.641647542301;10259.640912568322;10477.528511217166;6720.630365329758;6810.727570897108;6890.689918953559;7151.8569628608175;7518.135414427411;7728.45900786962;7968.36253099739;8081.555514446762;8139.2267734567495;8177.386695997943 08:54:41 INFO - PID 19085 | __end_tp_report 08:54:41 INFO - PID 19085 | __start_cc_report 08:54:41 INFO - PID 19085 | _x_x_mozilla_cycle_collect,3139 08:54:41 INFO - PID 19085 | __end_cc_report 08:54:41 INFO - PID 19085 | __startTimestamp1490284481851__endTimestamp 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | ------- Summary: start ------- 08:54:41 INFO - PID 19085 | Number of tests: 6 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#0] dojo.html Cycles:165 Average:5060.81 Median:5198.22 stddev:580.52 (11.2%) stddev-sans-first:550.32 08:54:41 INFO - PID 19085 | Values: 2638.4 2652.4 2669.2 2674.0 2680.8 4929.2 5039.7 5041.6 5082.0 5100.1 5113.3 5130.8 5167.8 5178.9 5210.6 4590.8 4616.3 4636.4 4717.4 4769.2 4618.2 4674.5 4716.0 4718.2 4734.1 4542.1 4589.3 4595.4 4622.9 4665.5 4650.2 4650.4 4663.7 4710.5 4726.8 4575.4 4622.7 4638.0 4656.5 4696.7 4997.4 5048.2 5057.1 5084.5 5105.5 5522.7 5528.5 5562.2 5569.6 5579.5 5581.9 5588.2 5611.0 5611.1 5616.1 5065.2 5123.4 5164.1 5198.2 5249.6 5553.1 5595.9 5613.3 5681.3 5689.4 5323.3 5336.2 5405.6 5473.8 5491.8 5535.1 5562.9 5582.5 5582.7 5587.1 5540.7 5546.7 5549.5 5552.1 5555.0 5374.4 5427.2 5437.9 5496.4 5551.9 4599.8 4602.5 4606.1 4638.5 4652.6 3946.3 3950.3 3964.1 3984.2 3991.2 4658.4 4721.0 4743.1 4772.0 4825.4 5585.4 5589.2 5591.4 5641.7 5644.9 4739.8 4767.9 4778.9 4780.2 4824.2 4763.1 4927.2 4937.8 4944.7 4979.4 5030.4 5068.5 5131.0 5183.2 5193.2 5596.7 5598.4 5602.0 5603.5 5638.8 5175.5 5179.6 5181.8 5183.1 5241.3 5287.1 5287.5 5333.8 5344.4 5492.5 5429.9 5460.3 5461.2 5464.1 5470.8 5247.0 5304.7 5308.8 5311.4 5319.5 5281.0 5287.0 5296.3 5307.0 5369.7 5286.4 5287.1 5296.3 5298.6 5299.5 5397.1 5404.5 5408.4 5414.2 5415.6 5176.0 5212.2 5213.1 5221.8 5265.3 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#1] ext.html Cycles:180 Average:31833.89 Median:28568.21 stddev:48872.55 (171.1%) stddev-sans-first:48953.27 08:54:41 INFO - PID 19085 | Values: 567.9 582.8 1040.5 1055.6 1055.7 30771.4 31574.5 31684.8 31743.2 31792.0 33241.0 33619.0 33643.0 33673.0 33738.3 5490.2 7309.2 7622.7 7625.7 7627.5 5638.7 7057.4 7200.4 7203.3 7206.3 7274.7 7334.3 7335.7 7339.0 7356.2 5538.9 7365.3 7387.8 7398.1 7400.2 5588.1 7169.0 7292.2 7298.1 7309.2 33962.7 34487.8 34519.3 34605.0 34663.7 2510.3 2521.9 2532.4 2556.5 2558.4 22824.2 22864.0 23068.7 23144.4 23363.2 2631.4 2634.9 2640.2 2642.4 2662.8 154285.0 154346.0 154554.0 154778.0 154848.0 30827.0 31461.7 41717.6 42006.6 42013.2 38848.0 39378.2 39450.8 39503.2 39569.4 11890.4 11998.7 12018.2 12018.3 12021.4 9465.6 9519.6 9522.2 9527.5 9775.1 12619.2 12676.7 12688.0 12723.5 12828.8 5140.1 5196.2 5264.7 5297.2 5312.9 845.3 857.5 888.1 891.8 912.4 18572.0 19506.8 20802.9 20819.2 20908.6 906.1 957.8 966.1 976.8 987.1 10592.0 10686.4 10923.2 10929.2 11000.7 185498.0 185510.0 185609.0 185799.0 186114.0 38333.4 38365.6 38549.2 38576.4 38578.0 1172.3 1229.3 1243.4 1244.3 1252.9 18817.9 19041.7 19601.2 19626.3 20194.9 30674.7 30706.0 30754.8 30768.0 30769.1 218521.0 218713.0 218735.0 218921.0 218933.0 20471.2 20881.6 25841.1 26297.5 29134.2 31062.0 31251.0 31657.0 31747.2 31992.0 25344.0 26177.2 26647.9 26697.7 27052.5 25188.5 25636.9 25877.2 26224.2 27023.3 28308.2 28398.4 28418.3 28514.4 28622.0 25695.0 25753.4 25907.7 25949.5 26167.5 19899.4 21398.8 21451.9 21475.9 21518.5 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#2] jquery.html Cycles:180 Average:26484.60 Median:222730.46 stddev:71255.30 (32.0%) stddev-sans-first:71428.28 08:54:41 INFO - PID 19085 | Values: 405.8 408.6 426.1 428.8 435.9 16778.9 17328.9 17409.7 17438.0 18443.3 18406.0 18515.7 18654.6 19163.3 19292.3 5638.9 5689.3 5770.7 5819.8 5883.9 5706.7 5803.7 5808.1 5809.2 5809.2 5522.7 5680.1 5714.2 5716.0 5765.2 5683.8 5725.5 5733.9 5736.9 5738.8 4948.2 5052.4 5083.7 5090.8 5091.3 22527.4 22672.5 22681.5 22855.2 22967.4 1539.4 1561.5 1567.8 1583.8 1602.3 13396.3 14048.4 14132.0 14227.6 14608.3 1737.9 1750.2 1770.9 1773.4 1775.5 85597.6 87560.3 87773.0 89230.6 89890.0 10741.4 10949.9 10988.5 11075.1 11166.6 15622.3 15631.4 15642.1 15664.1 15672.8 7406.1 7612.8 7642.1 7762.6 7783.7 6873.5 6976.4 7130.9 7136.5 7176.1 6782.9 6791.4 6985.4 7025.4 7027.4 1916.0 1924.9 1939.9 1950.4 2150.5 830.5 858.5 865.5 867.2 878.6 16343.5 16639.6 16767.8 17280.1 17442.2 856.8 857.5 861.8 871.6 874.0 9239.0 9378.4 9480.3 9596.4 9629.2 416986.0 428587.0 430764.0 434509.0 445025.0 55100.7 78121.6 82846.6 83392.6 85558.0 1032.5 1037.7 1077.7 1080.3 1080.7 16565.6 16901.7 17290.7 17298.1 17360.3 29044.6 30237.4 37810.6 39053.6 39199.0 49577.0 49773.0 49892.3 49987.0 50929.0 7742.0 7790.6 7791.9 7879.4 8179.0 12496.3 12703.7 12712.9 12927.2 12938.3 9745.4 9848.2 9989.2 10020.5 10240.5 8695.3 8999.8 9340.8 9351.9 9626.5 10948.2 11205.3 11227.4 11257.3 11552.0 8070.9 8308.2 8409.8 8516.7 8520.4 11058.1 11414.3 11446.2 11506.7 11691.2 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#3] mootools.html Cycles:180 Average:6131.40 Median:4815.45 stddev:3977.77 (82.6%) stddev-sans-first:3971.88 08:54:41 INFO - PID 19085 | Values: 1229.8 1297.9 1315.6 1318.0 1321.0 9389.0 9758.8 9770.6 9798.0 9800.1 9549.1 9736.6 9946.3 10026.6 10038.8 2156.6 2190.8 2190.9 2196.5 2197.5 1784.7 2093.5 2096.4 2122.9 2174.3 1751.5 2091.9 2149.6 2153.3 2155.3 2067.6 2089.3 2100.7 2102.9 2108.1 2028.4 2037.1 2042.4 2048.4 2049.2 9673.0 9884.8 9893.7 9898.1 9900.1 5109.7 5288.5 5292.0 5300.0 5300.8 3051.8 3178.4 3196.7 3281.9 3307.0 980.6 1032.4 1046.1 1064.2 1073.2 14744.3 14883.7 14938.3 14939.3 14940.4 4596.7 4751.9 4808.4 4822.5 4830.4 10655.6 10670.6 10686.1 10689.3 10694.4 5078.1 5082.5 5084.6 5087.7 5092.5 4651.9 4691.3 4866.9 4922.4 4933.2 3643.6 3796.5 3813.1 3859.5 3900.3 1059.6 1075.3 1084.6 1092.4 1097.8 553.7 615.5 672.2 686.8 689.2 5499.7 5578.9 5598.3 5626.4 5739.9 558.9 562.9 563.8 570.4 641.6 3268.8 3373.6 3453.1 3600.0 3629.4 12430.2 13149.0 13162.7 13200.5 13205.3 12665.8 13069.9 13070.2 13088.8 13102.3 8980.7 9184.2 9199.1 9209.1 9217.5 5501.6 5539.3 5547.1 5616.4 5741.4 11729.0 11733.1 11784.6 11820.6 11915.5 10936.4 11190.2 11313.9 11321.1 11343.7 8335.0 8400.3 8551.4 8571.1 8577.7 9119.9 9128.6 9223.6 9224.4 9235.0 7569.8 7592.0 7739.6 7857.4 7900.6 7218.1 7359.4 7362.7 7399.2 7399.8 7252.9 7388.0 7389.9 7398.7 7408.2 5224.0 5267.7 5311.7 5353.4 5539.0 5353.9 5359.3 5426.0 5472.8 5493.4 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#4] prototype.html Cycles:180 Average:4643.81 Median:5321.44 stddev:1350.48 (25.4%) stddev-sans-first:1315.72 08:54:41 INFO - PID 19085 | Values: 375.6 395.4 397.2 398.8 399.9 5339.8 5406.4 5444.0 5445.5 5450.5 5118.4 5284.6 5301.1 5330.7 5341.3 4120.5 4123.0 4130.3 4131.8 4142.2 4130.9 4153.3 4153.8 4157.3 4160.0 3922.7 4092.7 4109.9 4115.0 4132.4 4081.0 4085.8 4094.6 4107.2 4107.3 3612.2 3613.8 3618.1 3624.3 3641.3 4964.2 5207.4 5219.1 5230.9 5244.9 4694.8 4708.7 4709.7 4721.4 4738.5 5630.0 5633.5 5636.4 5641.9 5648.7 3803.3 3953.3 3964.4 3965.7 3966.9 5615.5 5737.3 5828.7 5911.9 5955.0 4050.7 4053.3 4089.6 4090.6 4098.6 4151.8 4152.4 4157.2 4157.6 4157.6 4379.7 4475.2 4715.5 4765.6 4772.1 5586.9 5597.3 5613.9 5620.4 5631.8 1415.2 1433.9 1437.9 1440.0 1441.3 940.5 962.1 962.3 972.3 974.7 4932.3 4942.1 4948.7 4956.1 4964.2 5792.0 5803.7 5829.2 5838.6 5849.1 4824.2 4895.9 4902.7 4960.5 4962.1 3055.3 3064.3 3069.4 3080.2 3096.6 5378.4 5388.5 5393.1 5407.5 5420.1 5796.6 5860.6 5888.6 5916.7 5919.9 5349.8 5372.1 5372.7 5375.3 5399.8 5652.0 5705.7 5770.1 5784.1 5802.7 5640.1 5651.8 5682.4 5715.3 5722.2 5129.4 5226.2 5312.2 5388.4 5391.4 4046.7 4056.7 4057.6 4060.4 4083.0 5761.9 5798.2 5810.4 5822.4 5847.0 5441.2 5614.3 5636.8 5646.8 5656.7 5575.8 5618.8 5628.7 5636.3 5649.8 5380.8 5482.6 5527.7 5564.5 5575.0 5623.2 5639.4 5640.9 5659.0 5695.1 5511.6 5519.8 5525.9 5541.1 5548.4 08:54:41 INFO - PID 19085 | 08:54:41 INFO - PID 19085 | [#5] yui.html Cycles:180 Average:13677.52 Median:26911.34 stddev:17329.91 (64.4%) stddev-sans-first:17351.08 08:54:41 INFO - PID 19085 | Values: 688.8 691.9 695.2 699.8 709.8 11007.9 11770.4 11786.2 13558.7 14151.9 14645.0 14817.5 14838.4 14849.4 15371.8 814.4 827.1 835.1 838.7 839.0 838.6 850.2 850.9 853.2 854.7 883.5 884.2 884.4 884.5 892.4 885.1 888.0 890.3 890.6 892.0 652.0 659.5 659.8 659.9 660.2 13066.0 13148.1 13333.0 13413.8 13692.5 12459.9 12615.4 12728.3 13050.0 13441.1 9806.4 9824.0 10082.8 10099.7 10161.3 2661.3 2675.4 2682.8 2777.0 2781.5 66175.3 66345.3 67103.3 67210.7 67944.7 10100.6 10282.9 10288.9 10365.0 10438.7 20867.2 20867.8 20868.9 20937.4 20963.9 5904.7 6103.9 6169.2 6205.8 6253.2 3278.6 3355.1 3387.5 3389.2 3480.3 3460.2 3505.5 3529.9 3550.6 3649.5 681.6 688.6 690.8 708.0 709.0 503.2 540.1 543.5 548.4 552.3 7830.8 8168.8 8428.0 8549.5 8637.8 482.9 487.8 504.9 507.6 525.9 3633.0 3733.0 3782.8 3788.5 3964.7 59087.0 60497.0 61431.1 63721.7 63783.4 53296.7 53444.0 53569.5 54387.0 55242.4 47433.0 47755.0 47946.3 48183.8 49296.8 7608.9 7631.6 7782.3 7842.5 7940.9 26080.0 26236.5 26390.7 26555.6 26880.9 32173.8 32228.0 32274.5 32281.8 32889.0 9805.3 9853.9 9948.1 10009.7 10118.1 9801.3 9819.5 9973.4 10217.3 10422.6 10096.5 10436.7 10593.3 10656.8 10808.4 9642.9 10192.9 10280.5 10290.6 10507.0 9708.9 10209.4 10238.6 10259.6 10477.5 6720.6 6810.7 6890.7 7151.9 7518.1 7728.5 7968.4 8081.6 8139.2 8177.4 08:54:41 INFO - PID 19085 | -------- Summary: end -------- 08:54:41 INFO - PID 19085 | 08:54:42 INFO - TEST-INFO | 19085: exit 0 08:54:42 INFO - TEST-OK | dromaeo_css | took 1123795ms 08:54:42 INFO - TEST-START | kraken 08:54:42 INFO - operating with platform_type : linux_ 08:54:42 INFO - Initialising browser for kraken test... 08:54:42 INFO - TEST-INFO | started process 19419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjo8UYL/profile http://localhost:38734/getInfo.html) 08:54:46 INFO - PID 19419 | __metrics Screen width/height:1600/1200 08:54:46 INFO - PID 19419 | colorDepth:24 08:54:46 INFO - PID 19419 | Browser inner width/height: 1024/697 08:54:46 INFO - PID 19419 | __metrics 08:54:47 INFO - PID 19419 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:54:47 INFO - PID 19419 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:54:48 INFO - TEST-INFO | 19419: exit 0 08:54:48 INFO - Browser initialized. 08:54:48 INFO - Running cycle 1/1 for kraken test... 08:54:48 INFO - TEST-INFO | started process 19511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjo8UYL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:01 INFO - PID 19511 | RSS: Main: 150323200 08:55:01 INFO - PID 19511 | 08:55:03 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38734/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:04 INFO - PID 19511 | RSS: Main: 205160448 08:55:04 INFO - PID 19511 | 08:55:07 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38734/tests/kraken/driver.html?testName=audio-dft) 08:55:08 INFO - PID 19511 | RSS: Main: 184692736 08:55:08 INFO - PID 19511 | 08:55:11 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38734/tests/kraken/driver.html?testName=audio-fft) 08:55:11 INFO - PID 19511 | RSS: Main: 265732096 08:55:11 INFO - PID 19511 | 08:55:13 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38734/tests/kraken/driver.html?testName=audio-oscillator) 08:55:14 INFO - PID 19511 | RSS: Main: 177053696 08:55:14 INFO - PID 19511 | 08:55:16 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38734/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:55:17 INFO - PID 19511 | RSS: Main: 180043776 08:55:17 INFO - PID 19511 | 08:55:20 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38734/tests/kraken/driver.html?testName=imaging-darkroom) 08:55:21 INFO - PID 19511 | RSS: Main: 219320320 08:55:21 INFO - PID 19511 | 08:55:24 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38734/tests/kraken/driver.html?testName=imaging-desaturate) 08:55:25 INFO - PID 19511 | RSS: Main: 214044672 08:55:25 INFO - PID 19511 | 08:55:28 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38734/tests/kraken/driver.html?testName=json-parse-financial) 08:55:28 INFO - PID 19511 | RSS: Main: 216461312 08:55:28 INFO - PID 19511 | 08:55:30 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38734/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:55:31 INFO - PID 19511 | RSS: Main: 179916800 08:55:31 INFO - PID 19511 | 08:55:32 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:55:33 INFO - PID 19511 | RSS: Main: 186060800 08:55:33 INFO - PID 19511 | 08:55:35 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:55:36 INFO - PID 19511 | RSS: Main: 196272128 08:55:36 INFO - PID 19511 | 08:55:39 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:55:39 INFO - PID 19511 | RSS: Main: 205668352 08:55:39 INFO - PID 19511 | 08:55:43 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:55:43 INFO - PID 19511 | RSS: Main: 202002432 08:55:43 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | Cycle 1(1): loaded http://localhost:38734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38734/tests/kraken/driver.html?testName=ai-astar) 08:55:45 INFO - PID 19511 | RSS: Main: 271441920 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | __start_tp_report 08:55:45 INFO - PID 19511 | _x_x_mozilla_page_load 08:55:45 INFO - PID 19511 | _x_x_mozilla_page_load_details 08:55:45 INFO - PID 19511 | |i|pagename|runs| 08:55:45 INFO - PID 19511 | |0;ai-astar;108;112;113;111;111;109;111;110;110;110 08:55:45 INFO - PID 19511 | |1;audio-beat-detection;151;123;123;154;125;124;124;127;166;124 08:55:45 INFO - PID 19511 | |2;audio-dft;162;161;162;162;162;163;163;162;162;161 08:55:45 INFO - PID 19511 | |3;audio-fft;76;76;76;110;75;74;75;75;102;76 08:55:45 INFO - PID 19511 | |4;audio-oscillator;84;83;119;83;86;83;108;84;84;118 08:55:45 INFO - PID 19511 | |5;imaging-gaussian-blur;114;113;113;113;113;113;114;114;113;113 08:55:45 INFO - PID 19511 | |6;imaging-darkroom;149;149;149;150;149;149;149;149;149;149 08:55:45 INFO - PID 19511 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;93;94 08:55:45 INFO - PID 19511 | |8;json-parse-financial;65;64;65;64;65;66;65;66;65;64 08:55:45 INFO - PID 19511 | |9;json-stringify-tinderbox;55;55;55;59;56;56;56;56;56;56 08:55:45 INFO - PID 19511 | |10;stanford-crypto-aes;83;82;81;82;81;83;82;83;82;82 08:55:45 INFO - PID 19511 | |11;stanford-crypto-ccm;139;143;134;135;139;135;141;133;179;140 08:55:45 INFO - PID 19511 | |12;stanford-crypto-pbkdf2;186;194;192;195;195;199;193;194;191;198 08:55:45 INFO - PID 19511 | |13;stanford-crypto-sha256-iterative;72;72;71;71;71;76;71;73;73;70 08:55:45 INFO - PID 19511 | __end_tp_report 08:55:45 INFO - PID 19511 | __start_cc_report 08:55:45 INFO - PID 19511 | _x_x_mozilla_cycle_collect,2002 08:55:45 INFO - PID 19511 | __end_cc_report 08:55:45 INFO - PID 19511 | __startTimestamp1490284545871__endTimestamp 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | ------- Summary: start ------- 08:55:45 INFO - PID 19511 | Number of tests: 14 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.43 (1.3%) stddev-sans-first:1.20 08:55:45 INFO - PID 19511 | Values: 108.0 112.0 113.0 111.0 111.0 109.0 111.0 110.0 110.0 110.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:126.00 stddev:16.28 (12.9%) stddev-sans-first:16.08 08:55:45 INFO - PID 19511 | Values: 151.0 123.0 123.0 154.0 125.0 124.0 124.0 127.0 166.0 124.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 08:55:45 INFO - PID 19511 | Values: 162.0 161.0 162.0 162.0 162.0 163.0 163.0 162.0 162.0 161.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.50 stddev:13.07 (17.3%) stddev-sans-first:13.71 08:55:45 INFO - PID 19511 | Values: 76.0 76.0 76.0 110.0 75.0 74.0 75.0 75.0 102.0 76.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:83.50 stddev:15.34 (18.4%) stddev-sans-first:15.90 08:55:45 INFO - PID 19511 | Values: 84.0 83.0 119.0 83.0 86.0 83.0 108.0 84.0 84.0 118.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 08:55:45 INFO - PID 19511 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 114.0 114.0 113.0 113.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 08:55:45 INFO - PID 19511 | Values: 149.0 149.0 149.0 150.0 149.0 149.0 149.0 149.0 149.0 149.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:55:45 INFO - PID 19511 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 08:55:45 INFO - PID 19511 | Values: 65.0 64.0 65.0 64.0 65.0 66.0 65.0 66.0 65.0 64.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#9] json-stringify-tinderbox Cycles:10 Average:56.00 Median:56.00 stddev:1.15 (2.1%) stddev-sans-first:1.17 08:55:45 INFO - PID 19511 | Values: 55.0 55.0 55.0 59.0 56.0 56.0 56.0 56.0 56.0 56.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 08:55:45 INFO - PID 19511 | Values: 83.0 82.0 81.0 82.0 81.0 83.0 82.0 83.0 82.0 82.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:139.50 stddev:13.48 (9.7%) stddev-sans-first:14.26 08:55:45 INFO - PID 19511 | Values: 139.0 143.0 134.0 135.0 139.0 135.0 141.0 133.0 179.0 140.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.70 Median:194.50 stddev:3.65 (1.9%) stddev-sans-first:2.60 08:55:45 INFO - PID 19511 | Values: 186.0 194.0 192.0 195.0 195.0 199.0 193.0 194.0 191.0 198.0 08:55:45 INFO - PID 19511 | 08:55:45 INFO - PID 19511 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:72.00 stddev:1.70 (2.4%) stddev-sans-first:1.80 08:55:45 INFO - PID 19511 | Values: 72.0 72.0 71.0 71.0 71.0 76.0 71.0 73.0 73.0 70.0 08:55:45 INFO - PID 19511 | -------- Summary: end -------- 08:55:45 INFO - PID 19511 | 08:55:47 INFO - TEST-INFO | 19511: exit 0 08:55:47 INFO - TEST-OK | kraken | took 64294ms 08:55:47 INFO - SUITE-END | took 1188s 08:55:47 INFO - Completed test suite (00:19:48) 08:55:47 INFO - javascript benchmark 08:55:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6447.703881471576, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2638.393169571, 2652.442986972736, 2669.159214847323, 2673.9598906016413, 2680.785537157027, 4929.162042452783, 5039.6976181429045, 5041.571466425354, 5082.034413461444, 5100.056489549436, 5113.284140220372, 5130.794768209276, 5167.7932882684745, 5178.948210517894, 5210.557102646279, 4590.816367345302, 4616.2613981762925, 4636.374089497908, 4717.386739723831, 4769.165396055694, 4618.1687296286655, 4674.462436819776, 4715.976420117895, 4718.174319494092, 4734.124187025412, 4542.091581683677, 4589.33454649074, 4595.448546174447, 4622.86131416058, 4665.463471700764, 4650.209464391028, 4650.372199753024, 4663.743494107823, 4710.505396933341, 4726.763661816895, 4575.428071491064, 4622.745748983804, 4637.976810115962, 4656.511066338019, 4696.671233013691, 4997.375328083989, 5048.242763585492, 5057.115004874169, 5084.51697088776, 5105.463926287724, 5522.723863806794, 5528.530074943635, 5562.193481945135, 5569.610127291091, 5579.497845193952, 5581.916271255894, 5588.161775733673, 5610.971945140289, 5611.13027480737, 5616.129499927518, 5065.189569668835, 5123.436421993578, 5164.096283150471, 5198.2202669599865, 5249.632525723239, 5553.111502159635, 5595.916061259084, 5613.270274864241, 5681.289838770153, 5689.431056894277, 5323.254744335796, 5336.199570064521, 5405.621606487587, 5473.75368108436, 5491.780328786884, 5535.059039963216, 5562.916556251659, 5582.49757521825, 5582.692951887685, 5587.106063029895, 5540.667559946416, 5546.667199968015, 5549.500544950893, 5552.139418390156, 5554.972225138848, 5374.355077390738, 5427.240186373911, 5437.891242175135, 5496.367917689437, 5551.916721249185, 4599.8390056348535, 4602.470715867651, 4606.124836281096, 4638.536146385334, 4652.55800698933, 3946.2699400610404, 3950.328444164499, 3964.1278918637854, 3984.2230765001127, 3991.221711766236, 4658.394408726844, 4721.0, 4743.051389722069, 4771.997880445146, 4825.372701548847, 5585.385607583163, 5589.161625756088, 5591.41290164535, 5641.746121424546, 5644.915326270109, 4739.810407583658, 4767.904641907143, 4778.904421911542, 4780.163471392492, 4824.179889418807, 4763.095011947719, 4927.187014142626, 4937.753112344369, 4944.728039957765, 4979.427365852974, 5030.421501527371, 5068.467810879952, 5130.97434512825, 5183.222516622537, 5193.246979188072, 5596.664200147841, 5598.384177740364, 5602.0196465619165, 5603.467670571289, 5638.830835074954, 5175.534201921694, 5179.637425380338, 5181.766820493011, 5183.092958732283, 5241.318628578413, 5287.12762394216, 5287.471252874681, 5333.7866485340155, 5344.35867695879, 5492.533134683436, 5429.891402172014, 5460.34475862899, 5461.208124821799, 5464.098423760035, 5470.781168753205, 5247.0, 5304.7082410466255, 5308.787648494017, 5311.389190243017, 5319.494648008433, 5281.0, 5287.048331300249, 5296.3379577552805, 5307.01820163271, 5369.677819330853, 5286.36563612369, 5287.101192797157, 5296.258523806747, 5298.629095963245, 5299.5230429261555, 5397.082495975772, 5404.5406140476925, 5408.4321146280645, 5414.242006119225, 5415.59383046281, 5175.9741201293755, 5212.244224587489, 5213.061648903234, 5221.817236396708, 5265.341832270966], "value": 5019.146422066591, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.860874085849, 582.8076734677556, 1040.4745603470246, 1055.6358056470524, 1055.7202341379536, 30771.38457230857, 31574.526382104286, 31684.841575792118, 31743.206419839516, 31792.0, 33241.0, 33619.0, 33643.0, 33673.0, 33738.325233495314, 5490.176473528963, 7309.232530584291, 7622.656980435866, 7625.694972201105, 7627.466077374586, 5638.746256418451, 7057.364837164654, 7200.35196832285, 7203.315685009942, 7206.315400036988, 7274.672639731225, 7334.339909408152, 7335.743248986287, 7339.0, 7356.154042285126, 5538.861528461781, 7365.3002964718535, 7387.7783666490095, 7398.112226532797, 7400.185979542246, 5588.133839254922, 7168.964155179243, 7292.19785823559, 7298.087739032621, 7309.232530584265, 33962.6603733962, 34487.82756086203, 34519.30961380783, 34605.0, 34663.65336346655, 2510.297116807297, 2521.8865151068244, 2532.354249666323, 2556.5398228318895, 2558.398776287571, 22824.201152959733, 22864.0, 23068.653970190462, 23144.42138946523, 23363.182288619988, 2631.355317947096, 2634.868256587163, 2640.2079376187066, 2642.365832200258, 2662.773664238542, 154285.0, 154346.0, 154554.0, 154778.0, 154848.0, 30827.0, 31461.685383145876, 41717.58282417198, 42006.579934200876, 42013.15973680509, 38848.0, 39378.21243575113, 39450.80274598609, 39503.209935801126, 39569.40645890314, 11890.40547972598, 11998.700032499257, 12018.158728888893, 12018.33899135556, 12021.398930053638, 9465.574049167804, 9519.619215231312, 9522.190613797904, 9527.475988820543, 9775.120239178508, 12619.179753316004, 12676.683082923, 12687.984961203081, 12723.491060357483, 12828.80756788649, 5140.100482415637, 5196.168613021974, 5264.6838290427395, 5297.198952356217, 5312.867178320495, 845.2815107158866, 857.5326447086228, 888.1429420609143, 891.7503099132254, 912.4023764434306, 18572.040781749878, 19506.80493195078, 20802.895985519976, 20819.1672333108, 20908.58182836335, 906.0939060939061, 957.7557722780647, 966.1256562810527, 976.7655762616921, 987.1313244344931, 10592.0, 10686.358818470917, 10923.235373523778, 10929.180311476512, 11000.724981875517, 185498.0, 185510.0, 185609.0, 185799.0, 186114.0, 38333.42499862504, 38365.616343836766, 38549.229015419536, 38576.42135367972, 38578.0, 1172.3259126002583, 1229.280870690638, 1243.3534562027514, 1244.2596654990266, 1252.8809763072493, 18817.90591047036, 19041.714374284675, 19601.215951361784, 19626.313079042164, 20194.89902550478, 30674.693253067184, 30706.0, 30754.846225768728, 30768.0, 30769.076927692204, 218521.0, 218713.0, 218735.0, 218921.0, 218933.0, 20471.181152753725, 20881.582368352243, 25841.095561655253, 26297.474050519264, 29134.23224793726, 31062.0, 31251.0, 31657.0, 31747.20631984219, 31992.0, 25344.0, 26177.214683559523, 26647.86676066685, 26697.7330226703, 27052.45895082048, 25188.496230075663, 25636.871815640803, 25877.223683289532, 26224.21327360182, 27023.324416889736, 28308.150755477367, 28398.43203135967, 28418.289542761595, 28514.429711406072, 28622.0, 25695.0, 25753.356166095997, 25907.740922590532, 25949.481010380063, 26167.476650467266, 19899.403017909484, 21398.78601213968, 21451.927403630067, 21475.892620536797, 21518.462038449165], "value": 14136.619397561855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.77276725034, 408.55671596317995, 426.1264407963675, 428.7963217471701, 435.91717573661003, 16778.916105419485, 17328.913355433237, 17409.65180696385, 17437.999999999985, 18443.262269509203, 18406.0, 18515.722264166016, 18654.626907461876, 19163.317135601028, 19292.3247686331, 5638.859028524279, 5689.260396148495, 5770.653760774346, 5819.7963071292515, 5883.852903677421, 5706.686132262724, 5803.738831752581, 5808.099744539603, 5809.157672137538, 5809.186713860063, 5522.723863806814, 5680.091185410335, 5714.171445140452, 5715.971125197463, 5765.1928729977835, 5683.772649094052, 5725.541956643458, 5733.88532229353, 5736.856578585569, 5738.770449182028, 4948.183549714328, 5052.368453943257, 5083.669561478492, 5090.847274581732, 5091.338126043628, 22527.436814079614, 22672.54654906909, 22681.54636907269, 22855.20006799754, 22967.425814354607, 1539.3688587678998, 1561.523735260745, 1567.7648352747065, 1583.754518049704, 1602.2789744614854, 13396.330183490787, 14048.367823447968, 14131.993193465387, 14227.644308892257, 14608.342624581814, 1737.8870373425814, 1750.1774166141893, 1770.8848924819845, 1773.379317238969, 1775.458485162025, 85597.57201214017, 87560.3085799421, 87773.0, 89230.553847231, 89890.0, 10741.355518668905, 10949.890501095046, 10988.450577471094, 11075.11399088071, 11166.60916867922, 15622.29699663518, 15631.3747450103, 15642.139682317582, 15664.138472384127, 15672.764908526382, 7406.148292946273, 7612.7716168515035, 7642.121156067013, 7762.611869406507, 7783.688652453839, 6873.518853680194, 6976.441884649216, 7130.92869071313, 7136.536125151849, 7176.102987126609, 6782.896347709663, 6791.422729068085, 6985.3713165815325, 7025.367716905503, 7027.383701748524, 1916.0, 1924.9422517324504, 1939.9030048497518, 1950.3661310074283, 2150.4838838678606, 830.464350493928, 858.4849090545623, 865.4807115730637, 867.2411639815161, 878.6178012564554, 16343.509694709177, 16639.584010399594, 16767.832321676626, 17280.13599320054, 17442.215100320514, 856.8029353248787, 857.498363457372, 861.8233262181363, 871.6067643832433, 873.9730816290833, 9239.0, 9378.390404623678, 9480.288978326653, 9596.376235544667, 9629.229661627052, 416986.0, 428587.0, 430764.0, 434509.0, 445025.0, 55100.72449637806, 78121.60939195381, 82846.58576707199, 83392.58303708442, 85558.0, 1032.4734385463469, 1037.7094413564214, 1077.6605369308643, 1080.308602494404, 1080.713610893114, 16565.58586035359, 16901.746473802657, 17290.740638890176, 17298.135093245037, 17360.305587776347, 29044.56433153547, 30237.395252095273, 37810.62189378071, 39053.609463905, 39199.0, 49577.0, 49773.0, 49892.25161622651, 49987.0, 50929.0, 7742.0, 7790.649420776192, 7791.883121753292, 7879.409044321585, 8179.0, 12496.31270280107, 12703.745925081632, 12712.936435317764, 12927.224366538037, 12938.288394138037, 9745.366551174151, 9848.212143028397, 9989.200863931013, 10020.498975051072, 10240.487975601041, 8695.260902823078, 8999.82000359976, 9340.766480838034, 9351.906480935104, 9626.47054412014, 10948.178886583379, 11205.327680339205, 11227.438628068727, 11257.324560526395, 11552.0, 8070.87893681607, 8308.169183081643, 8409.831803363775, 8516.74449766508, 8520.446170998865, 11058.11535077208, 11414.315141091562, 11446.198766086294, 11506.654800356002, 11691.181617287048], "value": 8783.546908234504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.8339724137243, 1297.8896793772528, 1315.6447759105042, 1318.0444177970978, 1320.9933950330253, 9388.953055234722, 9758.756031099227, 9770.560324785383, 9797.99999999999, 9800.06899344563, 9549.140577348036, 9736.561854716538, 9946.303758736873, 10026.649067282646, 10038.799224015533, 2156.6225910465646, 2190.791874771894, 2190.9342719718434, 2196.4728465168323, 2197.4726065744267, 1784.723367877981, 2093.5289559849066, 2096.350131459245, 2122.9363119106374, 2174.3042226487532, 1751.527087686324, 2091.8640288381207, 2149.6453085240914, 2153.3001774423296, 2155.2887547109417, 2067.5968186203695, 2089.3105275259127, 2100.6953991671276, 2102.915883364671, 2108.114591871418, 2028.4421784009369, 2037.1443993522607, 2042.4383294593956, 2048.354768247997, 2049.231538173185, 9672.95163524178, 9884.752881177956, 9893.653722119763, 9898.109170174646, 9900.059494348025, 5109.693418394908, 5288.497592728723, 5292.047431462335, 5300.01949639318, 5300.761465734051, 3051.816890986548, 3178.3802158579088, 3196.6963138502038, 3281.917952051194, 3306.9834650826833, 980.6371642492323, 1032.4203967929082, 1046.1055797293357, 1064.1912146768434, 1073.2326386633517, 14744.336504857307, 14883.734882534889, 14938.253087345589, 14939.253037348199, 14940.402383904631, 4596.701214421052, 4751.928721069186, 4808.374911261583, 4822.517748225219, 4830.372051633265, 10655.627053053106, 10670.62652807148, 10686.0916822071, 10689.30519516229, 10694.358338499715, 5078.08594453002, 5082.491750824888, 5084.6440749147205, 5087.669301495391, 5092.465291144451, 4651.906961860744, 4691.272852707836, 4866.8783280417565, 4922.384701912261, 4933.235348520985, 3643.5809881863925, 3796.5064541609413, 3813.065798879237, 3859.4789703390243, 3900.258950799339, 1059.6291298045621, 1075.3171735947728, 1084.5770149641646, 1092.431935393591, 1097.846301517788, 553.6822362776566, 615.5106690181227, 672.1530871102349, 686.7562015484531, 689.1798759476208, 5499.697516636624, 5578.888422231533, 5598.300212473441, 5626.43735626434, 5739.85650358736, 558.8714595643025, 562.8986782379129, 563.7660370946094, 570.4124751505956, 641.6214433484266, 3268.803871767654, 3373.6120346160014, 3453.1194545391268, 3599.9560127563236, 3629.3648611493413, 12430.192037517532, 13149.0, 13162.670933226555, 13200.537981170613, 13205.33973301331, 12665.810012849815, 13069.869301307055, 13070.215787052617, 13088.803667944989, 13102.279374634487, 8980.685676001309, 9184.173424391836, 9199.126083022236, 9209.079092090737, 9217.493037882981, 5501.614886957875, 5539.282822325092, 5547.11246200606, 5616.382197958303, 5741.3971532988435, 11729.0, 11733.061355091402, 11784.646460606196, 11820.645380638594, 11915.52337906474, 10936.398498082364, 11190.216684831983, 11313.943430282796, 11321.094312455149, 11343.716407089889, 8335.0, 8400.327973761961, 8551.35864810154, 8571.142885711379, 8577.699780507652, 9119.863202051842, 9128.634854605742, 9223.631054758003, 9224.40041397307, 9235.030321816113, 7569.848603028019, 7591.99944468117, 7739.613019348897, 7857.371410287049, 7900.604969751605, 7218.097737782778, 7359.4480413968095, 7362.7054917804835, 7399.186089530255, 7399.815004624712, 7252.927470725225, 7387.963060184665, 7389.926100738924, 7398.704051838082, 7408.222136675571, 5224.0, 5267.73661316925, 5311.707856067954, 5353.384360798558, 5538.97230513861, 5353.866153346042, 5359.276497672764, 5425.972870135624, 5472.808451704172, 5493.395726470165], "value": 4555.28085455993, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.62249938811493, 395.353596869119, 397.1897329447925, 398.76871414579546, 399.94600728901605, 5339.786408543659, 5406.432324605918, 5444.047291723942, 5445.48267914548, 5450.4549545045475, 5118.436971933093, 5284.603654725901, 5301.065111234391, 5330.733463326827, 5341.30563026806, 4120.484939382577, 4122.97938510307, 4130.256553820311, 4131.834726610932, 4142.212979533879, 4130.8967275818195, 4153.293940030202, 4153.833846646131, 4157.293260145782, 4159.979200103995, 3922.666573341269, 4092.7339722918064, 4109.938350924724, 4114.991827002388, 4132.380142978547, 4080.979595102035, 4085.754854708727, 4094.6314831664995, 4107.240160570304, 4107.322291821846, 3612.187257866985, 3613.8012409317475, 3618.113562177257, 3624.3294990426857, 3641.308151451216, 4964.205727083686, 5207.432908457718, 5219.086659834514, 5230.895382092375, 5244.895102097975, 4694.812207511695, 4708.717476951394, 4709.693869898446, 4721.409823772028, 4738.549837765441, 5630.0, 5633.464820842013, 5636.408177141382, 5641.90961552875, 5648.717564121777, 3803.315403227418, 3953.3081710700512, 3964.4053391991433, 3965.7422267552515, 3966.8809935701975, 5615.522680572116, 5737.340205876378, 5828.745016138243, 5911.940880591182, 5954.97022514889, 4050.736702114353, 4053.2906741320153, 4089.5705950875326, 4090.5909409058854, 4098.590140985878, 4151.813168407369, 4152.418661387408, 4157.230912281238, 4157.584241575818, 4157.625813676784, 4379.715318504351, 4475.172093162775, 4715.481297057321, 4765.642576806753, 4772.0694464579765, 5586.91619625706, 5597.257343901459, 5613.915791263134, 5620.409856965041, 5631.7747290108755, 1415.2074838090703, 1433.9498117565836, 1437.9209143497208, 1439.985600143985, 1441.2649548730014, 940.5156344482597, 962.1052421248166, 962.3119469579144, 972.2707969023232, 974.7222041718218, 4932.347663532757, 4942.110420124341, 4948.713334533048, 4956.132676781549, 4964.205727083649, 5791.971040144772, 5803.6807975560905, 5829.154772557956, 5838.591298609142, 5849.122631605208, 4824.228123500223, 4895.877603059952, 4902.7058376497525, 4960.454350021495, 4962.082014827269, 3055.266735983348, 3064.341166649181, 3069.4014667139695, 3080.1529579365406, 3096.5664806927048, 5378.408375078739, 5388.461153884658, 5393.056215162331, 5407.486288802557, 5420.105682562412, 5796.6096620141625, 5860.619059761102, 5888.558358123072, 5916.7337469813965, 5919.881602367928, 5349.759260833272, 5372.059889519397, 5372.731363431891, 5375.3012108425655, 5399.78400863961, 5651.971740141273, 5705.714714264187, 5770.13447982806, 5784.132380143013, 5802.738876750472, 5640.097584386478, 5651.8304450866535, 5682.374938756816, 5715.342735585295, 5722.198892155185, 5129.358830146231, 5226.242194881797, 5312.150055991023, 5388.353397592314, 5391.379991300893, 4046.67626589878, 4056.6957478189843, 4057.594240575919, 4060.4112403700706, 4082.9795851020554, 5761.913571296518, 5798.217240672624, 5810.448007439287, 5822.359540450462, 5847.0, 5441.156620723794, 5614.2701448811395, 5636.788090560549, 5646.774129034792, 5656.6888821115235, 5575.77696892136, 5618.831435056954, 5628.690422026828, 5636.267285252891, 5649.830505084854, 5380.838574842761, 5482.643628164156, 5527.738634205838, 5564.4991950724625, 5575.0, 5623.156526520891, 5639.407862174413, 5640.943590564042, 5659.0, 5695.117256825199, 5511.614187007031, 5519.751611177587, 5525.917111243254, 5541.141123125761, 5548.389677135431], "value": 4233.162470938102, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.7761477519807, 691.875940861555, 695.1935754524751, 699.7865650976453, 709.8367375503631, 11007.944960275197, 11770.411479426026, 11786.233894796844, 13558.728825423486, 14151.929240353784, 14645.04807187532, 14817.481388151416, 14838.406463741438, 14849.406023759037, 15371.769423458629, 814.352589691194, 827.065416079829, 835.1481488881337, 838.6771093129158, 838.9874151887726, 838.6268110690745, 850.2093053460279, 850.9149085091473, 853.1681610429844, 854.700854700856, 883.5405589093667, 884.1644645809712, 884.3765145572356, 884.4516399832113, 892.3753372639146, 885.0927799006038, 887.951162686052, 890.2877697841701, 890.6036813617943, 891.9598618062138, 651.9967400163016, 659.5020759326726, 659.7558903205796, 659.8548319369731, 660.1582981698325, 13066.0200484964, 13148.145370550883, 13333.0, 13413.798793018113, 13692.452301907912, 12459.875401245961, 12615.432305546181, 12728.299943503103, 13050.0, 13441.126326788728, 9806.41963068534, 9824.0, 10082.798344033079, 10099.69700908974, 10161.33951293171, 2661.308059904419, 2675.357914100621, 2682.825616334932, 2776.9583456248174, 2781.5132351838347, 66175.33824661787, 66345.33654663488, 67103.32896671069, 67210.66394668045, 67944.6602766983, 10100.598623910124, 10282.948585257176, 10288.948555257175, 10365.0, 10438.68683939483, 20867.1653133876, 20867.791322086887, 20868.895655521625, 20937.37187884366, 20963.895180524305, 5904.7342869570975, 6103.908441373383, 6169.2288463942, 6205.751769929152, 6253.187085678833, 3278.60656721195, 3355.127666806648, 3387.4749413840896, 3389.220479289728, 3480.3213373391945, 3460.169559305799, 3505.4566542186003, 3529.9117522061642, 3550.55618047744, 3649.489071529988, 681.6455443169524, 688.6384648059709, 690.7858563845224, 707.9575225486487, 709.018010056076, 503.1597232621531, 540.0980362794144, 543.4646872830259, 548.4241546376289, 552.2654869024188, 7830.8433831323055, 8168.795780105426, 8428.0, 8549.487030778173, 8637.827243455222, 482.8551434569615, 487.7780609822522, 504.90154419888506, 507.6066048812126, 525.9368875734937, 3632.9636703633155, 3732.9813350933073, 3782.7730336179916, 3788.5453745550158, 3964.702647301464, 59087.0, 60497.0, 61431.07853382203, 63721.68139159275, 63783.36216637867, 53296.73351633217, 53444.0, 53569.46430535723, 54387.0, 55242.44757552454, 47433.0, 47755.0, 47946.280805787945, 48183.75908120437, 49296.75351623219, 7608.885866711893, 7631.618419078913, 7782.338501227233, 7842.529448233125, 7940.92059079425, 26080.0, 26236.475270494866, 26390.73609263883, 26555.601665975413, 26880.865595671898, 32173.839130804197, 32228.0, 32274.515882262254, 32281.838590806896, 32889.0, 9805.264605154785, 9853.852192217266, 9948.104670579683, 10009.74975625586, 10118.08937195656, 9801.264905132286, 9819.5090245486, 9973.401595904268, 10217.284790064625, 10422.635207767691, 10096.495175241062, 10436.739081523023, 10593.311434756715, 10656.840147397641, 10808.351498910091, 9642.903570964481, 10192.898071019492, 10280.485975701335, 10290.639827605997, 10507.0, 9708.85436718464, 10209.438480883622, 10238.641647542301, 10259.640912568322, 10477.528511217166, 6720.630365329758, 6810.727570897108, 6890.689918953559, 7151.8569628608175, 7518.135414427411, 7728.45900786962, 7968.36253099739, 8081.555514446762, 8139.2267734567495, 8177.386695997943], "value": 5978.220485640807, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 113.0, 111.0, 111.0, 109.0, 111.0, 110.0, 110.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 123.0, 154.0, 125.0, 124.0, 124.0, 127.0, 166.0, 124.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 163.0, 163.0, 162.0, 162.0, 161.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 110.0, 75.0, 74.0, 75.0, 75.0, 102.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 83.0, 86.0, 83.0, 108.0, 84.0, 84.0, 118.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 64.0, 65.0, 66.0, 65.0, 66.0, 65.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 59.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 82.0, 81.0, 83.0, 82.0, 83.0, 82.0, 82.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 134.0, 135.0, 139.0, 135.0, 141.0, 133.0, 179.0, 140.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 194.0, 192.0, 195.0, 195.0, 199.0, 193.0, 194.0, 191.0, 198.0], "value": 193.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 71.0, 71.0, 71.0, 76.0, 71.0, 73.0, 73.0, 70.0], "value": 72.0, "unit": "score"}]}]} 08:55:47 INFO - Return code: 0 08:55:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:55:47 ERROR - # TBPL SUCCESS # 08:55:47 INFO - Running post-action listener: _resource_record_post_action 08:55:47 INFO - [mozharness: 2017-03-23 15:55:47.672949Z] Finished run-tests step (success) 08:55:47 INFO - Running post-run listener: _resource_record_post_run 08:55:47 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 356163584; Read time: 16; Write time: 218464 08:55:47 INFO - TinderboxPrint: CPU usage
13.0% 08:55:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:55:47 INFO - TinderboxPrint: I/O write bytes / time
356,163,584 / 218,464 08:55:47 INFO - TinderboxPrint: CPU idle
8,364.2 (87.0%) 08:55:47 INFO - TinderboxPrint: CPU user
1,201.1 (12.5%) 08:55:47 INFO - TinderboxPrint: Swap in / out
0 / 0 08:55:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106209280; Read time: 0; Write time: 84188 08:55:47 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249417728; Read time: 16; Write time: 134276 08:55:48 INFO - Running post-run listener: _upload_blobber_files 08:55:48 INFO - Blob upload gear active. 08:55:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:55:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:55:48 INFO - (blobuploader) - INFO - Open directory for files ... 08:55:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:55:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:49 INFO - (blobuploader) - INFO - Done attempting. 08:55:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:55:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:55:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:50 INFO - (blobuploader) - INFO - Done attempting. 08:55:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:55:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:55:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:53 INFO - (blobuploader) - INFO - Done attempting. 08:55:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:55:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:54 INFO - (blobuploader) - INFO - Done attempting. 08:55:54 INFO - (blobuploader) - INFO - Iteration through files over. 08:55:54 INFO - Return code: 0 08:55:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:55:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:55:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0545d1ba8681af3ae22af28869f294784f07530925002b77ae6712d38f585dac8b677ef73e15a249caf02852972443bcb0a82f6ec2a83ee703c1b53bf3ff0b3a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd0c956d61dd20c88824fa8f9c1a4e9bc01a417b853b6032c76f7f876c3fa9de611fdf584ea1cefb983145559d0f80a0147084fa7d25b3d2620515955226e9af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fd6d0c2fb09531d362e279c911845256ed4c95253a43b28dec5913eee5450e36651bd87e2b7d6f6d336c5c0363bcb1f3c1bfc7ec322607a5bc1c533cb983f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a4b51ef4a4a98d19de2e23634b2d609b03fda9670ea1d73b04e17af3c78d327d85938dad6a2e420d4668b6ac20f130cbfe8da89ee0440998496961ff73e3f91"} 08:55:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:55:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:55:54 INFO - Contents: 08:55:54 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0545d1ba8681af3ae22af28869f294784f07530925002b77ae6712d38f585dac8b677ef73e15a249caf02852972443bcb0a82f6ec2a83ee703c1b53bf3ff0b3a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd0c956d61dd20c88824fa8f9c1a4e9bc01a417b853b6032c76f7f876c3fa9de611fdf584ea1cefb983145559d0f80a0147084fa7d25b3d2620515955226e9af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fd6d0c2fb09531d362e279c911845256ed4c95253a43b28dec5913eee5450e36651bd87e2b7d6f6d336c5c0363bcb1f3c1bfc7ec322607a5bc1c533cb983f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a4b51ef4a4a98d19de2e23634b2d609b03fda9670ea1d73b04e17af3c78d327d85938dad6a2e420d4668b6ac20f130cbfe8da89ee0440998496961ff73e3f91"} 08:55:54 INFO - Running post-run listener: copy_logs_to_upload_dir 08:55:54 INFO - Copying logs to upload dir... 08:55:54 INFO - mkdir: /builds/slave/test/build/upload/logs 08:55:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.201982 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-23 08:55:54.430629) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:55:54.434192) ========= 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=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0545d1ba8681af3ae22af28869f294784f07530925002b77ae6712d38f585dac8b677ef73e15a249caf02852972443bcb0a82f6ec2a83ee703c1b53bf3ff0b3a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd0c956d61dd20c88824fa8f9c1a4e9bc01a417b853b6032c76f7f876c3fa9de611fdf584ea1cefb983145559d0f80a0147084fa7d25b3d2620515955226e9af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fd6d0c2fb09531d362e279c911845256ed4c95253a43b28dec5913eee5450e36651bd87e2b7d6f6d336c5c0363bcb1f3c1bfc7ec322607a5bc1c533cb983f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a4b51ef4a4a98d19de2e23634b2d609b03fda9670ea1d73b04e17af3c78d327d85938dad6a2e420d4668b6ac20f130cbfe8da89ee0440998496961ff73e3f91"} build_url:https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/JzD_l16pRq2tcIikmBN-2A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0545d1ba8681af3ae22af28869f294784f07530925002b77ae6712d38f585dac8b677ef73e15a249caf02852972443bcb0a82f6ec2a83ee703c1b53bf3ff0b3a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fd0c956d61dd20c88824fa8f9c1a4e9bc01a417b853b6032c76f7f876c3fa9de611fdf584ea1cefb983145559d0f80a0147084fa7d25b3d2620515955226e9af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fd6d0c2fb09531d362e279c911845256ed4c95253a43b28dec5913eee5450e36651bd87e2b7d6f6d336c5c0363bcb1f3c1bfc7ec322607a5bc1c533cb983f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a4b51ef4a4a98d19de2e23634b2d609b03fda9670ea1d73b04e17af3c78d327d85938dad6a2e420d4668b6ac20f130cbfe8da89ee0440998496961ff73e3f91"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:55:54.460160) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:55:54.460524) ========= 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=00204705a9daa57ed339a427000001b9-1490283288.913801-220132694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004382 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:55:54.543449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:55:54.543762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:55:54.544050) ========= ========= Total master_lag: 0.17 =========