builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-020 starttime: 1475606878.8 results: success (0) buildid: 20161004113000 builduid: bb54f5ded4fb47609cc82ce3c553bad7 revision: df26b07efb46d767397e45dcc93a7132fbbeff3a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.800846) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.801365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.801669) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.821514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.821836) ========= 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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-04 11:47:59-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 81.6M=0s 2016-10-04 11:47:59 (81.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139862 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.975511) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:58.975813) ========= 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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016641 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.025135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.025600) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev df26b07efb46d767397e45dcc93a7132fbbeff3a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev df26b07efb46d767397e45dcc93a7132fbbeff3a --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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-04 11:47:59,483 truncating revision to first 12 chars 2016-10-04 11:47:59,483 Setting DEBUG logging. 2016-10-04 11:47:59,483 attempt 1/10 2016-10-04 11:47:59,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/df26b07efb46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 11:47:59,797 unpacking tar archive at: autoland-df26b07efb46/testing/mozharness/ program finished with exit code 0 elapsedTime=0.681436 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.720943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.721242) ========= script_repo_revision: df26b07efb46d767397e45dcc93a7132fbbeff3a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.721639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.721909) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 11:47:59.732441) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-10-04 11:47:59.732792) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:48:00 INFO - MultiFileLogger online at 20161004 11:48:00 in /builds/slave/test 11:48:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 11:48:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:48:00 INFO - {'append_to_log': False, 11:48:00 INFO - 'base_work_dir': '/builds/slave/test', 11:48:00 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 11:48:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:48:00 INFO - 'branch': 'Autoland-Non-PGO', 11:48:00 INFO - 'buildbot_json_path': 'buildprops.json', 11:48:00 INFO - 'config_files': ('talos/linux_config.py',), 11:48:00 INFO - 'default_actions': ('clobber', 11:48:00 INFO - 'read-buildbot-config', 11:48:00 INFO - 'download-and-extract', 11:48:00 INFO - 'populate-webroot', 11:48:00 INFO - 'create-virtualenv', 11:48:00 INFO - 'install', 11:48:00 INFO - 'run-tests'), 11:48:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:48:00 INFO - 'download_minidump_stackwalk': True, 11:48:00 INFO - 'download_symbols': 'ondemand', 11:48:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:48:00 INFO - 'tooltool.py': '/tools/tooltool.py', 11:48:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:48:00 INFO - '/tools/misc-python/virtualenv.py')}, 11:48:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:48:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:48:00 INFO - 'installer_path': 'installer.exe', 11:48:00 INFO - 'log_level': 'info', 11:48:00 INFO - 'log_name': 'talos', 11:48:00 INFO - 'log_to_console': True, 11:48:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:48:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:48:00 INFO - 'opt_config_files': (), 11:48:00 INFO - 'pip_index': False, 11:48:00 INFO - 'sps_profile': False, 11:48:00 INFO - 'sps_profile_interval': 0, 11:48:00 INFO - 'suite': 'dromaeojs', 11:48:00 INFO - 'system_bits': '32', 11:48:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:48:00 INFO - 'title': 'talos-linux64-ix-020', 11:48:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:48:00 INFO - 'use_talos_json': True, 11:48:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:48:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:48:00 INFO - 'work_dir': 'build'} 11:48:00 INFO - [mozharness: 2016-10-04 18:48:00.282792Z] Running clobber step. 11:48:00 INFO - Running pre-action listener: _resource_record_pre_action 11:48:00 INFO - Running main action method: clobber 11:48:00 INFO - rmtree: /builds/slave/test/build 11:48:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:48:01 INFO - Running post-action listener: _resource_record_post_action 11:48:01 INFO - [mozharness: 2016-10-04 18:48:01.257945Z] Finished clobber step (success) 11:48:01 INFO - [mozharness: 2016-10-04 18:48:01.258047Z] Running read-buildbot-config step. 11:48:01 INFO - Running pre-action listener: _resource_record_pre_action 11:48:01 INFO - Running main action method: read_buildbot_config 11:48:01 INFO - Using buildbot properties: 11:48:01 INFO - { 11:48:01 INFO - "project": "", 11:48:01 INFO - "product": "firefox", 11:48:01 INFO - "script_repo_revision": "production", 11:48:01 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 11:48:01 INFO - "repository": "", 11:48:01 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 11:48:01 INFO - "buildid": "20161004113000", 11:48:01 INFO - "pgo_build": "False", 11:48:01 INFO - "basedir": "/builds/slave/test", 11:48:01 INFO - "buildnumber": 627, 11:48:01 INFO - "slavename": "talos-linux64-ix-020", 11:48:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:48:01 INFO - "platform": "ubuntu64_hw", 11:48:01 INFO - "scheduler": "tests-autoland-linux64-talos", 11:48:01 INFO - "branch": "autoland", 11:48:01 INFO - "repo_path": "integration/autoland", 11:48:01 INFO - "revision": "df26b07efb46d767397e45dcc93a7132fbbeff3a", 11:48:01 INFO - "stage_platform": "linux64", 11:48:01 INFO - "builduid": "bb54f5ded4fb47609cc82ce3c553bad7", 11:48:01 INFO - "slavebuilddir": "test" 11:48:01 INFO - } 11:48:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 11:48:01 INFO - Running post-action listener: _resource_record_post_action 11:48:01 INFO - [mozharness: 2016-10-04 18:48:01.260486Z] Finished read-buildbot-config step (success) 11:48:01 INFO - [mozharness: 2016-10-04 18:48:01.260575Z] Running download-and-extract step. 11:48:01 INFO - Running pre-action listener: _resource_record_pre_action 11:48:01 INFO - Running main action method: download_and_extract 11:48:01 INFO - mkdir: /builds/slave/test/build/tests 11:48:01 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')]} 11:48:01 INFO - trying https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:48:01 INFO - Downloading https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:48:01 INFO - Downloaded 1201 bytes. 11:48:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:48:01 INFO - Using the following test package requirements: 11:48:01 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 11:48:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:48:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:48:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'jsshell-linux-x86_64.zip'], 11:48:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:48:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 11:48:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:48:01 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:48:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:48:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:48:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:48:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 11:48:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:48:01 INFO - Fetch https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:48:02 INFO - Http code: 200 11:48:02 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 11:48:02 INFO - x-amz-cf-id: slYZG2EmZ0gJ7lyu03Iv2RyGMmM8tJzyLLOQ2yXR5kW8JE9h60lnsA== 11:48:02 INFO - x-amz-version-id: W8sIZ8HH6zq0FY5btaZulwN7c4ZIWmAL 11:48:02 INFO - Expected file size: 20421874 11:48:02 INFO - Obtained file size: 20421874 11:48:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:48:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 11:48:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:48:07 INFO - Fetch https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:48:07 INFO - Http code: 200 11:48:07 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 11:48:07 INFO - x-amz-cf-id: 9osZwbIzo6sEdV8C_tmWNoPpkcBJ-0x14ile2WX4m8mnruP6gIVzzQ== 11:48:07 INFO - x-amz-version-id: W8sIZ8HH6zq0FY5btaZulwN7c4ZIWmAL 11:48:07 INFO - Expected file size: 20421874 11:48:07 INFO - Obtained file size: 20421874 11:48:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 11:48:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:48:12 INFO - Fetch https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 11:48:12 INFO - Http code: 200 11:48:12 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 11:48:12 INFO - x-amz-cf-id: Vs06LhtvMxgj9s-tyTsHvAzsfxZ6CVIUjugrxw1YB8Ro8ozActETuA== 11:48:12 INFO - x-amz-version-id: gxDUr_E2u.ffCedG56vU8mQMJNSctd_S 11:48:13 INFO - Expected file size: 14409006 11:48:13 INFO - Obtained file size: 14409006 11:48:13 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')]} 11:48:13 INFO - trying https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:48:13 INFO - Downloading https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:48:15 INFO - Downloaded 55794701 bytes. 11:48:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:48:15 INFO - mkdir: /builds/slave/test/properties 11:48:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:48:15 INFO - Writing to file /builds/slave/test/properties/build_url 11:48:15 INFO - Contents: 11:48:15 INFO - build_url:https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:48:16 INFO - Running post-action listener: _resource_record_post_action 11:48:16 INFO - Running post-action listener: set_extra_try_arguments 11:48:16 INFO - [mozharness: 2016-10-04 18:48:16.543426Z] Finished download-and-extract step (success) 11:48:16 INFO - [mozharness: 2016-10-04 18:48:16.543654Z] Running populate-webroot step. 11:48:16 INFO - Running pre-action listener: _resource_record_pre_action 11:48:16 INFO - Running main action method: populate_webroot 11:48:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:48:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:48:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:48:16 INFO - '--tppagecycles', 11:48:16 INFO - '7'], 11:48:16 INFO - 'tests': ['tsvgm']}}, 11:48:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:48:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:48:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:48:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:48:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:48:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:48:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['damp', 'tps']}, 11:48:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'tests': ['damp', 'tps']}, 11:48:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['dromaeo_dom']}, 11:48:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:48:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['basic_compositor_video']}, 11:48:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:48:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'other-e10s': {'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['a11yr', 11:48:16 INFO - 'ts_paint', 11:48:16 INFO - 'tpaint', 11:48:16 INFO - 'sessionrestore', 11:48:16 INFO - 'sessionrestore_no_auto_restore', 11:48:16 INFO - 'tabpaint']}, 11:48:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['tsvgx', 11:48:16 INFO - 'tsvgr_opacity', 11:48:16 INFO - 'tart', 11:48:16 INFO - 'tscrollx', 11:48:16 INFO - 'cart']}, 11:48:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:48:16 INFO - 'tsvgr_opacity', 11:48:16 INFO - 'tart', 11:48:16 INFO - 'tscrollx', 11:48:16 INFO - 'cart']}, 11:48:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'talos_options': ['--disable-e10s'], 11:48:16 INFO - 'tests': ['tp5o']}, 11:48:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'tests': ['tp5o']}, 11:48:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'talos_options': ['--disable-e10s', 11:48:16 INFO - '--xperf_path', 11:48:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:48:16 INFO - 'tests': ['tp5n']}, 11:48:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:48:16 INFO - 'talos_options': ['--xperf_path', 11:48:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:48:16 INFO - 'tests': ['tp5n']}}, 11:48:16 INFO - 'talos.zip': {'path': '', 11:48:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:48:16 INFO - Running post-action listener: _resource_record_post_action 11:48:16 INFO - [mozharness: 2016-10-04 18:48:16.561170Z] Finished populate-webroot step (success) 11:48:16 INFO - [mozharness: 2016-10-04 18:48:16.561258Z] Running create-virtualenv step. 11:48:16 INFO - Running pre-action listener: _resource_record_pre_action 11:48:16 INFO - Running main action method: create_virtualenv 11:48:16 INFO - Creating virtualenv /builds/slave/test/build/venv 11:48:16 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 11:48:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:48:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:48:16 INFO - Using real prefix '/usr' 11:48:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:48:17 INFO - Installing distribute.............................................................................................................................................................................................done. 11:48:19 INFO - Installing pip.................done. 11:48:19 INFO - Return code: 0 11:48:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:48:19 INFO - Reading from file tmpfile_stdout 11:48:19 INFO - Output received: 11:48:19 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) 11:48:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:48:19 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')]} 11:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:19 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')]} 11:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:19 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:19 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 11:48:19 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 11:48:19 INFO - Using env: {'DISPLAY': ':0', 11:48:19 INFO - 'HOME': '/home/cltbld', 11:48:19 INFO - 'LANG': 'en_US.UTF-8', 11:48:19 INFO - 'LANGUAGE': 'en_US:en', 11:48:19 INFO - 'LOGNAME': 'cltbld', 11:48:19 INFO - 'MAIL': '/var/mail/cltbld', 11:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:19 INFO - 'MOZ_NO_REMOTE': '1', 11:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:19 INFO - 'NO_EM_RESTART': '1', 11:48:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:19 INFO - 'PWD': '/builds/slave/test', 11:48:19 INFO - 'SHELL': '/bin/bash', 11:48:19 INFO - 'SHLVL': '1', 11:48:19 INFO - 'TERM': 'linux', 11:48:19 INFO - 'TMOUT': '86400', 11:48:19 INFO - 'USER': 'cltbld', 11:48:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:19 INFO - '_': '/tools/buildbot/bin/python'} 11:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:19 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 11:48:19 INFO - Cleaning up... 11:48:19 INFO - Return code: 0 11:48:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:48:19 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')]} 11:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:19 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')]} 11:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:19 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:19 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 11:48:19 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 11:48:19 INFO - Using env: {'DISPLAY': ':0', 11:48:19 INFO - 'HOME': '/home/cltbld', 11:48:19 INFO - 'LANG': 'en_US.UTF-8', 11:48:19 INFO - 'LANGUAGE': 'en_US:en', 11:48:19 INFO - 'LOGNAME': 'cltbld', 11:48:19 INFO - 'MAIL': '/var/mail/cltbld', 11:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:19 INFO - 'MOZ_NO_REMOTE': '1', 11:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:19 INFO - 'NO_EM_RESTART': '1', 11:48:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:19 INFO - 'PWD': '/builds/slave/test', 11:48:19 INFO - 'SHELL': '/bin/bash', 11:48:19 INFO - 'SHLVL': '1', 11:48:19 INFO - 'TERM': 'linux', 11:48:19 INFO - 'TMOUT': '86400', 11:48:19 INFO - 'USER': 'cltbld', 11:48:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:19 INFO - '_': '/tools/buildbot/bin/python'} 11:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:19 INFO - Downloading/unpacking psutil>=3.1.1 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:19 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 11:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:48:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:48:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:48:21 INFO - Installing collected packages: psutil 11:48:21 INFO - Running setup.py install for psutil 11:48:21 INFO - building 'psutil._psutil_linux' extension 11:48:21 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 11:48:22 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 11:48:22 INFO - building 'psutil._psutil_posix' extension 11:48:22 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 11:48:22 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 11:48:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:48:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:48:22 INFO - Successfully installed psutil 11:48:22 INFO - Cleaning up... 11:48:22 INFO - Return code: 0 11:48:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:48: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')]} 11:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48: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')]} 11:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:48: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 mozsystemmonitor==0.3 11:48:22 INFO - Using env: {'DISPLAY': ':0', 11:48:22 INFO - 'HOME': '/home/cltbld', 11:48:22 INFO - 'LANG': 'en_US.UTF-8', 11:48:22 INFO - 'LANGUAGE': 'en_US:en', 11:48:22 INFO - 'LOGNAME': 'cltbld', 11:48:22 INFO - 'MAIL': '/var/mail/cltbld', 11:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:22 INFO - 'MOZ_NO_REMOTE': '1', 11:48:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:22 INFO - 'NO_EM_RESTART': '1', 11:48:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:22 INFO - 'PWD': '/builds/slave/test', 11:48:22 INFO - 'SHELL': '/bin/bash', 11:48:22 INFO - 'SHLVL': '1', 11:48:22 INFO - 'TERM': 'linux', 11:48:22 INFO - 'TMOUT': '86400', 11:48:22 INFO - 'USER': 'cltbld', 11:48:22 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:22 INFO - '_': '/tools/buildbot/bin/python'} 11:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:22 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 11:48:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:48:24 INFO - Installing collected packages: mozsystemmonitor 11:48:24 INFO - Running setup.py install for mozsystemmonitor 11:48:24 INFO - Successfully installed mozsystemmonitor 11:48:24 INFO - Cleaning up... 11:48:24 INFO - Return code: 0 11:48:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:24 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:24 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 11:48:24 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 11:48:24 INFO - Using env: {'DISPLAY': ':0', 11:48:24 INFO - 'HOME': '/home/cltbld', 11:48:24 INFO - 'LANG': 'en_US.UTF-8', 11:48:24 INFO - 'LANGUAGE': 'en_US:en', 11:48:24 INFO - 'LOGNAME': 'cltbld', 11:48:24 INFO - 'MAIL': '/var/mail/cltbld', 11:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:24 INFO - 'MOZ_NO_REMOTE': '1', 11:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:24 INFO - 'NO_EM_RESTART': '1', 11:48:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:24 INFO - 'PWD': '/builds/slave/test', 11:48:24 INFO - 'SHELL': '/bin/bash', 11:48:24 INFO - 'SHLVL': '1', 11:48:24 INFO - 'TERM': 'linux', 11:48:24 INFO - 'TMOUT': '86400', 11:48:24 INFO - 'USER': 'cltbld', 11:48:24 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:24 INFO - '_': '/tools/buildbot/bin/python'} 11:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:24 INFO - Downloading/unpacking jsonschema==2.5.1 11:48:24 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 11:48:24 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 11:48:24 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 11:48:24 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 11:48:24 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 11:48:24 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 11:48:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:48:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:48: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 11:48: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 11:48: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 11:48: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 11:48: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 11:48: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 11:48:26 INFO - Downloading functools32-3.2.3-2.tar.gz 11:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:48:27 INFO - warning: no files found matching '*.txt' 11:48:27 INFO - no previously-included directories found matching 'build' 11:48:27 INFO - no previously-included directories found matching 'dist' 11:48:27 INFO - no previously-included directories found matching '.git*' 11:48:27 INFO - Installing collected packages: jsonschema, functools32 11:48:27 INFO - Running setup.py install for functools32 11:48:27 INFO - warning: no files found matching '*.txt' 11:48:27 INFO - no previously-included directories found matching 'build' 11:48:27 INFO - no previously-included directories found matching 'dist' 11:48:27 INFO - no previously-included directories found matching '.git*' 11:48:27 INFO - Successfully installed jsonschema functools32 11:48:27 INFO - Cleaning up... 11:48:27 INFO - Return code: 0 11:48:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:48:27 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')]} 11:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:27 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')]} 11:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:27 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:27 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 11:48:27 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 11:48:27 INFO - Using env: {'DISPLAY': ':0', 11:48:27 INFO - 'HOME': '/home/cltbld', 11:48:27 INFO - 'LANG': 'en_US.UTF-8', 11:48:27 INFO - 'LANGUAGE': 'en_US:en', 11:48:27 INFO - 'LOGNAME': 'cltbld', 11:48:27 INFO - 'MAIL': '/var/mail/cltbld', 11:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:27 INFO - 'MOZ_NO_REMOTE': '1', 11:48:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:27 INFO - 'NO_EM_RESTART': '1', 11:48:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:27 INFO - 'PWD': '/builds/slave/test', 11:48:27 INFO - 'SHELL': '/bin/bash', 11:48:27 INFO - 'SHLVL': '1', 11:48:27 INFO - 'TERM': 'linux', 11:48:27 INFO - 'TMOUT': '86400', 11:48:27 INFO - 'USER': 'cltbld', 11:48:27 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:27 INFO - '_': '/tools/buildbot/bin/python'} 11:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:48:27 INFO - Cleaning up... 11:48:27 INFO - Return code: 0 11:48:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:48:27 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')]} 11:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:27 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')]} 11:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:27 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:27 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 11:48:27 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 11:48:27 INFO - Using env: {'DISPLAY': ':0', 11:48:27 INFO - 'HOME': '/home/cltbld', 11:48:27 INFO - 'LANG': 'en_US.UTF-8', 11:48:27 INFO - 'LANGUAGE': 'en_US:en', 11:48:27 INFO - 'LOGNAME': 'cltbld', 11:48:27 INFO - 'MAIL': '/var/mail/cltbld', 11:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:27 INFO - 'MOZ_NO_REMOTE': '1', 11:48:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:27 INFO - 'NO_EM_RESTART': '1', 11:48:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:27 INFO - 'PWD': '/builds/slave/test', 11:48:27 INFO - 'SHELL': '/bin/bash', 11:48:27 INFO - 'SHLVL': '1', 11:48:27 INFO - 'TERM': 'linux', 11:48:27 INFO - 'TMOUT': '86400', 11:48:27 INFO - 'USER': 'cltbld', 11:48:27 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:27 INFO - '_': '/tools/buildbot/bin/python'} 11:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:27 INFO - Downloading/unpacking blobuploader==1.2.4 11:48:27 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 11:48:27 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 11:48:27 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 11:48:27 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 11:48:27 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 11:48:27 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 11:48:29 INFO - Downloading blobuploader-1.2.4.tar.gz 11:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:48:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:48:29 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 11:48:29 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 11:48:29 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 11:48:29 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 11:48:29 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 11:48:29 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 11:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:48:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:48: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 11:48: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 11:48: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 11:48: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 11:48: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 11:48: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 11:48:30 INFO - Downloading docopt-0.6.1.tar.gz 11:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:48:30 INFO - Installing collected packages: blobuploader, requests, docopt 11:48:30 INFO - Running setup.py install for blobuploader 11:48:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:48:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:48:30 INFO - Running setup.py install for requests 11:48:30 INFO - Running setup.py install for docopt 11:48:30 INFO - Successfully installed blobuploader requests docopt 11:48:30 INFO - Cleaning up... 11:48:30 INFO - Return code: 0 11:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:30 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:30 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 11:48:30 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 11:48:30 INFO - Using env: {'DISPLAY': ':0', 11:48:30 INFO - 'HOME': '/home/cltbld', 11:48:30 INFO - 'LANG': 'en_US.UTF-8', 11:48:30 INFO - 'LANGUAGE': 'en_US:en', 11:48:30 INFO - 'LOGNAME': 'cltbld', 11:48:30 INFO - 'MAIL': '/var/mail/cltbld', 11:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:30 INFO - 'MOZ_NO_REMOTE': '1', 11:48:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:30 INFO - 'NO_EM_RESTART': '1', 11:48:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:30 INFO - 'PWD': '/builds/slave/test', 11:48:30 INFO - 'SHELL': '/bin/bash', 11:48:30 INFO - 'SHLVL': '1', 11:48:30 INFO - 'TERM': 'linux', 11:48:30 INFO - 'TMOUT': '86400', 11:48:30 INFO - 'USER': 'cltbld', 11:48:30 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:30 INFO - '_': '/tools/buildbot/bin/python'} 11:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:48:31 INFO - Running setup.py (path:/tmp/pip-BnVkOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:48:31 INFO - Running setup.py (path:/tmp/pip-CcUwUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:48:31 INFO - Running setup.py (path:/tmp/pip-ahmUvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:48:31 INFO - Running setup.py (path:/tmp/pip-k2GHWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:48:31 INFO - Running setup.py (path:/tmp/pip-NjEIZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:48:31 INFO - Running setup.py (path:/tmp/pip-hmQiNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:48:31 INFO - Running setup.py (path:/tmp/pip-SyzgIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:48:31 INFO - Running setup.py (path:/tmp/pip-zat9oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:48:31 INFO - Running setup.py (path:/tmp/pip-KIz88M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:48:31 INFO - Running setup.py (path:/tmp/pip-_0yrxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:48:31 INFO - Running setup.py (path:/tmp/pip-zmrZqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:48:31 INFO - Running setup.py (path:/tmp/pip-kEsSz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:48:32 INFO - Running setup.py (path:/tmp/pip-M2Wr6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:48:32 INFO - Running setup.py (path:/tmp/pip-S8ycSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:32 INFO - Running setup.py (path:/tmp/pip-cpff3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:48:32 INFO - Running setup.py (path:/tmp/pip-6jsi6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:48:32 INFO - Running setup.py (path:/tmp/pip-DfCEb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:48:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:48:32 INFO - Running setup.py install for manifestparser 11:48:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:48:32 INFO - Running setup.py install for mozcrash 11:48:32 INFO - Running setup.py install for mozdebug 11:48:32 INFO - Running setup.py install for mozdevice 11:48:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:48:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:48:32 INFO - Running setup.py install for mozfile 11:48:32 INFO - Running setup.py install for mozhttpd 11:48:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozinfo 11:48:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozInstall 11:48:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozleak 11:48:33 INFO - Running setup.py install for mozlog 11:48:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for moznetwork 11:48:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozprocess 11:48:33 INFO - Running setup.py install for mozprofile 11:48:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozrunner 11:48:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:48:33 INFO - Running setup.py install for mozscreenshot 11:48:33 INFO - Running setup.py install for moztest 11:48:34 INFO - Running setup.py install for mozversion 11:48:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:48:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:48:34 INFO - Cleaning up... 11:48:34 INFO - Return code: 0 11:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:34 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:34 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 11:48:34 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 11:48:34 INFO - Using env: {'DISPLAY': ':0', 11:48:34 INFO - 'HOME': '/home/cltbld', 11:48:34 INFO - 'LANG': 'en_US.UTF-8', 11:48:34 INFO - 'LANGUAGE': 'en_US:en', 11:48:34 INFO - 'LOGNAME': 'cltbld', 11:48:34 INFO - 'MAIL': '/var/mail/cltbld', 11:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:34 INFO - 'MOZ_NO_REMOTE': '1', 11:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:34 INFO - 'NO_EM_RESTART': '1', 11:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:34 INFO - 'PWD': '/builds/slave/test', 11:48:34 INFO - 'SHELL': '/bin/bash', 11:48:34 INFO - 'SHLVL': '1', 11:48:34 INFO - 'TERM': 'linux', 11:48:34 INFO - 'TMOUT': '86400', 11:48:34 INFO - 'USER': 'cltbld', 11:48:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:34 INFO - '_': '/tools/buildbot/bin/python'} 11:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:48:34 INFO - Running setup.py (path:/tmp/pip-noWePQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:48:34 INFO - Running setup.py (path:/tmp/pip-anuorv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:48:34 INFO - Running setup.py (path:/tmp/pip-l1q4wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:48:34 INFO - Running setup.py (path:/tmp/pip-1RsqH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:48:34 INFO - Running setup.py (path:/tmp/pip-n7SA8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:48:34 INFO - Running setup.py (path:/tmp/pip-URu6Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:48:34 INFO - Running setup.py (path:/tmp/pip-xJZhVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:48:34 INFO - Running setup.py (path:/tmp/pip-2XX0T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:48:34 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)) 11:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:48:34 INFO - Running setup.py (path:/tmp/pip-Th1BRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:48:35 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)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:48:35 INFO - Running setup.py (path:/tmp/pip-MlHlup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:48:35 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)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:48:35 INFO - Running setup.py (path:/tmp/pip-UICcRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:48:35 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)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:48:35 INFO - Running setup.py (path:/tmp/pip-xKYbEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:48:35 INFO - Running setup.py (path:/tmp/pip-dfFPqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:48:35 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)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:48:35 INFO - Running setup.py (path:/tmp/pip-0f4WFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:35 INFO - Running setup.py (path:/tmp/pip-XS62qc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:48:35 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)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:48:35 INFO - Running setup.py (path:/tmp/pip-n868v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:48:35 INFO - Running setup.py (path:/tmp/pip-KLLMIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:48:35 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)) 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:48:35 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)) 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:48:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:48:35 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 11:48:35 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 11:48:35 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 11:48:35 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 11:48:35 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 11:48:35 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 11:48:37 INFO - Downloading blessings-1.6.tar.gz 11:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:48:37 INFO - Installing collected packages: blessings 11:48:37 INFO - Running setup.py install for blessings 11:48:37 INFO - Successfully installed blessings 11:48:37 INFO - Cleaning up... 11:48:37 INFO - Return code: 0 11:48:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:48:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:38 INFO - Reading from file tmpfile_stdout 11:48:38 INFO - Current package versions: 11:48:38 INFO - argparse == 1.2.1 11:48:38 INFO - blessings == 1.6 11:48:38 INFO - blobuploader == 1.2.4 11:48:38 INFO - docopt == 0.6.1 11:48:38 INFO - functools32 == 3.2.3-2 11:48:38 INFO - jsonschema == 2.5.1 11:48:38 INFO - manifestparser == 1.1 11:48:38 INFO - mozInstall == 1.12 11:48:38 INFO - mozcrash == 0.17 11:48:38 INFO - mozdebug == 0.1 11:48:38 INFO - mozdevice == 0.48 11:48:38 INFO - mozfile == 1.2 11:48:38 INFO - mozhttpd == 0.7 11:48:38 INFO - mozinfo == 0.9 11:48:38 INFO - mozleak == 0.1 11:48:38 INFO - mozlog == 3.4 11:48:38 INFO - moznetwork == 0.27 11:48:38 INFO - mozprocess == 0.23 11:48:38 INFO - mozprofile == 0.28 11:48:38 INFO - mozrunner == 6.12 11:48:38 INFO - mozscreenshot == 0.1 11:48:38 INFO - mozsystemmonitor == 0.3 11:48:38 INFO - moztest == 0.7 11:48:38 INFO - mozversion == 1.4 11:48:38 INFO - psutil == 3.1.1 11:48:38 INFO - requests == 1.2.3 11:48:38 INFO - wsgiref == 0.1.2 11:48:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:48: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')]} 11:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48: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')]} 11:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:38 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 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:38 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 11:48:38 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 11:48:38 INFO - Using env: {'DISPLAY': ':0', 11:48:38 INFO - 'HOME': '/home/cltbld', 11:48:38 INFO - 'LANG': 'en_US.UTF-8', 11:48:38 INFO - 'LANGUAGE': 'en_US:en', 11:48:38 INFO - 'LOGNAME': 'cltbld', 11:48:38 INFO - 'MAIL': '/var/mail/cltbld', 11:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:38 INFO - 'MOZ_NO_REMOTE': '1', 11:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:38 INFO - 'NO_EM_RESTART': '1', 11:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:38 INFO - 'PWD': '/builds/slave/test', 11:48:38 INFO - 'SHELL': '/bin/bash', 11:48:38 INFO - 'SHLVL': '1', 11:48:38 INFO - 'TERM': 'linux', 11:48:38 INFO - 'TMOUT': '86400', 11:48:38 INFO - 'USER': 'cltbld', 11:48:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:38 INFO - '_': '/tools/buildbot/bin/python'} 11:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 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)) 11:48:38 INFO - Cleaning up... 11:48:38 INFO - Return code: 0 11:48:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:48: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')]} 11:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48: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')]} 11:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7184b4ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', '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 11:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:48:38 INFO - Using env: {'DISPLAY': ':0', 11:48:38 INFO - 'HOME': '/home/cltbld', 11:48:38 INFO - 'LANG': 'en_US.UTF-8', 11:48:38 INFO - 'LANGUAGE': 'en_US:en', 11:48:38 INFO - 'LOGNAME': 'cltbld', 11:48:38 INFO - 'MAIL': '/var/mail/cltbld', 11:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:38 INFO - 'MOZ_NO_REMOTE': '1', 11:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:38 INFO - 'NO_EM_RESTART': '1', 11:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:38 INFO - 'PWD': '/builds/slave/test', 11:48:38 INFO - 'SHELL': '/bin/bash', 11:48:38 INFO - 'SHLVL': '1', 11:48:38 INFO - 'TERM': 'linux', 11:48:38 INFO - 'TMOUT': '86400', 11:48:38 INFO - 'USER': 'cltbld', 11:48:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:38 INFO - '_': '/tools/buildbot/bin/python'} 11:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:48:38 INFO - Cleaning up... 11:48:38 INFO - Return code: 0 11:48:38 INFO - Running post-action listener: _resource_record_post_action 11:48:38 INFO - Running post-action listener: _start_resource_monitoring 11:48:38 INFO - Starting resource monitoring. 11:48:38 INFO - [mozharness: 2016-10-04 18:48:38.516506Z] Finished create-virtualenv step (success) 11:48:38 INFO - [mozharness: 2016-10-04 18:48:38.517016Z] Running install step. 11:48:38 INFO - Running pre-action listener: _resource_record_pre_action 11:48:38 INFO - Running main action method: install 11:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:38 INFO - Reading from file tmpfile_stdout 11:48:38 INFO - Detecting whether we're running mozinstall >=1.0... 11:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:48:38 INFO - Reading from file tmpfile_stdout 11:48:38 INFO - Output received: 11:48:38 INFO - Usage: mozinstall [options] installer 11:48:38 INFO - Options: 11:48:38 INFO - -h, --help show this help message and exit 11:48:38 INFO - -d DEST, --destination=DEST 11:48:38 INFO - Directory to install application into. [default: 11:48:38 INFO - "/builds/slave/test"] 11:48:38 INFO - --app=APP Application being installed. [default: firefox] 11:48:38 INFO - mkdir: /builds/slave/test/build/application 11:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:48:54 INFO - Reading from file tmpfile_stdout 11:48:54 INFO - Output received: 11:48:54 INFO - /builds/slave/test/build/application/firefox/firefox 11:48:54 INFO - Running post-action listener: _resource_record_post_action 11:48:54 INFO - [mozharness: 2016-10-04 18:48:54.933038Z] Finished install step (success) 11:48:54 INFO - [mozharness: 2016-10-04 18:48:54.933284Z] Running run-tests step. 11:48:54 INFO - Running pre-action listener: _resource_record_pre_action 11:48:54 INFO - Running main action method: run_tests 11:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:48:54 INFO - Python 2.7.3 11:48:54 INFO - Return code: 0 11:48:54 INFO - grabbing minidump binary from tooltool 11:48: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')]} 11:48:54 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 0x26ddf60>, '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 0x26ded40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26df1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:48:54 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 11:48:54 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 11:48:54 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 11:48:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:48:55 INFO - Return code: 0 11:48:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:48:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:48:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:48:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:48:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 11:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 11:48:55 INFO - Using env: {'DISPLAY': ':0', 11:48:55 INFO - 'HOME': '/home/cltbld', 11:48:55 INFO - 'LANG': 'en_US.UTF-8', 11:48:55 INFO - 'LANGUAGE': 'en_US:en', 11:48:55 INFO - 'LOGNAME': 'cltbld', 11:48:55 INFO - 'MAIL': '/var/mail/cltbld', 11:48:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:48:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:55 INFO - 'MOZ_NO_REMOTE': '1', 11:48:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:55 INFO - 'NO_EM_RESTART': '1', 11:48:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:55 INFO - 'PWD': '/builds/slave/test', 11:48:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:48:55 INFO - 'SHELL': '/bin/bash', 11:48:55 INFO - 'SHLVL': '1', 11:48:55 INFO - 'TERM': 'linux', 11:48:55 INFO - 'TMOUT': '86400', 11:48:55 INFO - 'USER': 'cltbld', 11:48:55 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257', 11:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:55 INFO - '_': '/tools/buildbot/bin/python'} 11:48:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 11:48:55 INFO - mozversion application_buildid: 20161004113000 11:48:55 INFO - mozversion application_changeset: df26b07efb46d767397e45dcc93a7132fbbeff3a 11:48:55 INFO - mozversion application_display_name: Nightly 11:48:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:48:55 INFO - mozversion application_name: Firefox 11:48:55 INFO - mozversion application_remotingname: firefox 11:48:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:48:55 INFO - mozversion application_vendor: Mozilla 11:48:55 INFO - mozversion application_version: 52.0a1 11:48:55 INFO - mozversion platform_buildid: 20161004113000 11:48:55 INFO - mozversion platform_changeset: df26b07efb46d767397e45dcc93a7132fbbeff3a 11:48:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:48:55 INFO - mozversion platform_version: 52.0a1 11:48:55 INFO - using testdate: 1475606935 11:48:55 INFO - actual date: 1475606935 11:48:55 INFO - starting webserver on 'localhost:34257' 11:48:55 INFO - SUITE-START | Running 2 tests 11:48:55 INFO - TEST-START | dromaeo_css 11:48:55 INFO - operating with platform_type : linux_ 11:48:55 INFO - Initialising browser for dromaeo_css test... 11:48:55 INFO - TEST-INFO | started process 2006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIh67AD/profile http://localhost:34257/getInfo.html) 11:49:00 INFO - PROCESS | 2006 | __metrics Screen width/height:1600/1200 11:49:00 INFO - PROCESS | 2006 | colorDepth:24 11:49:00 INFO - PROCESS | 2006 | Browser inner width/height: 1024/697 11:49:00 INFO - PROCESS | 2006 | __metrics 11:49:00 INFO - PROCESS | 2006 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:49:00 INFO - PROCESS | 2006 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:49:01 INFO - PROCESS | 2006 | console.error: PushService: 11:49:01 INFO - PROCESS | 2006 | stateChangeProcessEnqueue: Error transitioning state 11:49:01 INFO - PROCESS | 2006 | UnknownError 11:49:02 INFO - TEST-INFO | 2006: exit 0 11:49:02 INFO - Browser initialized. 11:49:02 INFO - Running cycle 1/1 for dromaeo_css test... 11:49:02 INFO - TEST-INFO | started process 2087 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIh67AD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:49:14 INFO - PROCESS | 2087 | RSS: Main: 175562752 11:49:14 INFO - PROCESS | 2087 | 11:50:05 INFO - PROCESS | 2087 | 1475607005914 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:52:18 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34257/tests/dromaeo/cssquery-ext.html) 11:52:20 INFO - PROCESS | 2087 | RSS: Main: 261664768 11:52:20 INFO - PROCESS | 2087 | 11:55:23 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-ext.html (next: http://localhost:34257/tests/dromaeo/cssquery-jquery.html) 11:55:23 INFO - PROCESS | 2087 | RSS: Main: 274046976 11:55:23 INFO - PROCESS | 2087 | 11:58:25 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34257/tests/dromaeo/cssquery-mootools.html) 11:58:26 INFO - PROCESS | 2087 | RSS: Main: 218456064 11:58:26 INFO - PROCESS | 2087 | 12:01:28 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34257/tests/dromaeo/cssquery-prototype.html) 12:01:29 INFO - PROCESS | 2087 | RSS: Main: 225558528 12:01:29 INFO - PROCESS | 2087 | 12:04:32 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34257/tests/dromaeo/cssquery-yui.html) 12:04:33 INFO - PROCESS | 2087 | RSS: Main: 219004928 12:04:33 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:34257/tests/dromaeo/cssquery-yui.html (next: http://localhost:34257/tests/dromaeo/cssquery-dojo.html) 12:07:36 INFO - PROCESS | 2087 | RSS: Main: 311144448 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | __start_tp_report 12:07:36 INFO - PROCESS | 2087 | _x_x_mozilla_page_load 12:07:36 INFO - PROCESS | 2087 | _x_x_mozilla_page_load_details 12:07:36 INFO - PROCESS | 2087 | |i|pagename|runs| 12:07:36 INFO - PROCESS | 2087 | |0;dojo.html;2510.1967370441453;2536.3278731136243;2568.601866710659;2572.356910772307;2588.0424243030084;4472.642188624902;4527.049319642871;4579.12996530659;4596.931046034304;4603.493615702274;4541.1598854212;4574.725516469014;4612.33121197427;4640.791164397602;4668.5098064703225;4124.505059392877;4135.503739551258;4191.203671302443;4192.643625291854;4232.0477892474255;4235.576442355755;4253.44705188325;4289.6997210195295;4292.570742925714;4342.066455712022;4098.139390727951;4224.134052519226;4226.218149642326;4290.2706539888295;4315.4821421429315;4132.607402296807;4166.895827604304;4183.037901282722;4216.7680777557225;4218.388333691628;4186.623203911632;4188.120494696117;4193.454850869368;4243.384709217177;4270.0605866709275;4571.200039993021;4573.931391029138;4611.100835337111;4616.976915115437;4644.373009643722;4723.811047558093;4735.786889589976;4762.976185119087;4771.594414474739;4933.01339732055;4804.303376010459;4806.134895718769;4807.543283388072;4836.733979631119;4851.539103785135;4479.910401791978;4484.865454036384;4485;4535.138323718483;4540.682152249311;4855.247436647325;4884.145274576935;4884.267359895973;4908.901821963541;4949.232868905326;4797.736124513115;4800.159972004955;4830.29960655703;4855.757212139379;4869.732164730974;4861.6353773467135;4880.219164933594;4880.853574392774;4882.707037577757;4934.8766280843265;4663.603593694506;4719.079779442975;4832.492588278251;4835.975820120877;4890.975545122323;4750.3824502815105;4793.113274044313;4797.18447863864;4803.759812009455;4860.854174374775;4071.2875246831686;4075.5720649331392;4075.8369665213654;4127.690423218271;4130.463039804867;3552.431610942237;3571.8392672329805;3575.0883524701553;3605.7656252344036;3631.6368363164;4181.393697913785;4212.389203565466;4217.7891105444605;4266.679999000087;4307.353896915488;4854.271859221145;4881;4882.853514394574;4883.58489528387;4939.53074457926;4250.808713607833;4252.234597772431;4257.361395790656;4263.381809637585;4279.679024073207;4256.3189889617515;4297.376880352332;4311.417958575615;4333.479982402069;4345.695801293942;4630.467496237976;4651.232546629768;4654.627629789677;4689.484156742756;4709.058188362273;4871.780769865293;4878.56092951636;4881.829135980366;4882.511748825089;4942.481039490802;4531.750753708578;4589.0363023765385;4598.954010459986;4619.653525985497;4623.098495793288;4805.038992201504;4825.227963525959;4848.806047758191;4861.222204447272;4862.902741945212;4858.975705121452;4859.975700121477;4865.343178670976;4866.610671146371;4921.015796840575;4622.329762184397;4730.574248317668;4738.147133516;4740.028294199749;4801.831935882227;4609.492955774795;4696.4599071105895;4772.642051846056;4789.832355867528;4849.3210950467665;4728.62171026324;4728.739919304471;4736.881577960578;4780.545848144421;4785.641076919175;4669.322948172564;4700.952990470051;4701.412323459567;4728.219843725747;4771.880702982315;4704.341392205162;4715.882102947454;4755.548222918817;4757.143714131351;4776.307435421914 12:07:36 INFO - PROCESS | 2087 | |1;ext.html;540.878302381964;555.9193916882052;559.8796258804356;569.9943000569996;585.3268740947907;27753.16740497786;37637.62362376376;39023.80488097563;39048.414273785915;43272.91817704551;39128.60871391285;39530;39532.20935581287;39537.60462395375;39571.60428395715;7104.396126329269;7512.248775122499;7650.464467487278;7650.885236721454;7651.23487651236;7217.133943926736;7313.524620899624;7322.340989310961;7322.633868306563;9549.761255968588;9584.089511496397;9922.355046921963;9927.553260103312;9938.90061099387;9974.501274936261;9520.8571871422;9555.092266234731;9566.282528810367;9571.138597526186;9587.137157655774;7064.823379415556;7312.634368281618;9339.673111441067;9618.470984095871;9680.95159524205;33876.6612333876;37269;37305;37320.440193397124;37339.813300933325;2848.2167403963867;2899.9565006524917;2910.63617047869;2957.07789936543;3234.110619579611;24527;24800.25599232026;27167.184984450498;27183.320416989536;27686.861565692245;3370.8314584270934;3395.2190996070794;3400.7619466637343;3458.515807786912;3468.271662950783;243593;246018.7699061511;246241;246833.76583117148;246844;44023.559764402584;45494.77252613782;51143.7442812791;52963;53048.204276935874;54406.927099476816;54560.72719636376;54626.45373546293;54655.72672136693;54666;14420.495282665057;14586.927065364605;14597.41610335596;14614.707705845824;14721.55835324942;10663.84004239937;10765.730856728487;10770.138388928866;10853.620123295805;10878.292910960763;15371.308291126703;16039.438619648256;16444;17265;17359.21883515245;6647.471081651247;6749.662516874137;6755;6834.6582670866255;6838.247792742794;776.3013288040809;832.142892820396;847.8431490174338;893.1024320557798;952.6665667016489;24934.251972440492;25977.382875605817;26108.73891261101;26268.21195364142;26391.340216494358;948.0756262644005;971.7133445633414;1014.8224060789332;1055.730788648905;1059.523214553454;12975.545855894998;13943.65140871466;13978.720425591431;14177.362018709182;14211.64470888236;370818;373782;373815;374827;375372;47124.76437617859;47533;47600;47637.28544071842;47667.284990725166;1039.9064084232455;1093.7320356512705;1150.3500522204927;1164.1618035014944;1204.2232759869835;24763.876180618983;28326.15021549357;28391.86432542675;28535.857320714094;28642.57036144501;39198.6080139195;39935.40096898607;39980.400293996194;39984;40026;242540;242809;243394;243995;244030;24002.27993160208;24562.385940351633;24761.380965476008;24926.252212433657;25743.069269805215;39190;39931;40236;40261.59738402579;40351.798241008604;34305.48541771826;34483;34546;34623;34651;33915;34744.30511389809;35016;35049.47425788564;35455.290894182486;34537;34821.30357392889;35082.82458587691;35418.29163416769;35595.11012224715;29104.56343154812;31191.532127017657;31279.687203127676;31611;31755.20611984719;24512.632310515;24565.631515526922;24633.63049554294;24949;24988.50022999566 12:07:36 INFO - PROCESS | 2087 | |2;jquery.html;339.2858033838768;344.4110570923721;347.6193568042993;350.4043126684635;373.6319725070805;12160.695982600428;15214.695706085886;15770.369185232606;16308.021518708885;17105.144742762845;14109.788353174685;14279.857201428009;14349.139051656883;14401;14639.707205855902;3924.862629807957;4977.278294647274;5028.4340104274415;5204.9219261711105;5207.06272870885;5024.371953505812;5156.071907056728;5171.767270472819;5173.224016397533;5175.042617115827;4909.459959404463;5136.178211486163;5137.460566640505;5137.871553211181;5140.563052140573;5018.372703412056;5045.596352291826;5059.139946209153;5081.872953176163;5092.567131793802;4430.977845110754;4552.704074235164;4557.0430209656015;4561.384213131218;4567.703099298568;19287.710684339745;19529.414117576496;19911.701324480146;19991.200351985903;20240.291589794433;1932.420273917819;1953.4823271832975;2055.095757866534;2239.3393948785147;2323.814094872423;12848.100632955697;12898.419571119322;13019;13410.26243556604;13540.59378218655;1581.533447632951;1639.426200829712;1654.7269700499403;1748.7376893465955;1769.5841477252836;61411;63743;71924.92112618368;71983;73004.63497682531;9532.666356677482;9751.21990240779;9776.217902567778;9998.600055997678;10160.949195253976;13751.17492950406;13774.724505510034;13791.448342066406;13798.241096739575;13800.447982080605;6977.976386847758;7189.137303523611;7200.675969581382;7303.671334789948;7313.561186328731;6662.200535935719;6782.9321706783285;6873.559886240313;6912.481563882729;6923.134608173978;4107.322291821816;4156.896077598024;4201.789910504463;4328.783560821947;4381.802818873159;1766.5053784940237;1777.129206688739;1779.6173822628198;1813.0934532733634;1827.2782251010776;793.2741541489472;804.7706403674924;846.3694547562105;857.5455008845322;885.0521230361045;15673.608159796097;16917.407890723767;17000.82999170017;17214.913925430545;17287.13564321779;831.4470876866936;840.785599672092;846.6317152038882;847.3814115695577;876.5179151466668;8814.338924580683;8949.657551367347;9411.6705915294;9429.952850235704;9562.665306714232;325002;334818;372351;372576;373047;75044;76067;76539;78590;79056.60471697604;894.2622336572354;898.5462341517492;902;908.5911339897073;918.2975024106564;14682.412703492168;14720.19038952868;15167.084836913393;15276.388944442542;15593.922030389775;36861.63138368582;38602.61397386102;38894;39610.60389396069;40195.3970690434;51575;52269.7386513065;52429.7378513105;52495.475045249055;53308.2003769936;7261.237570055279;7345.641056404582;7392.593407362647;7456.142543607338;7565.962170189334;8488.618012189343;10890.29213101146;11231.213815033196;11446.370449625017;11600.593979210687;9411.43531388119;9610.375325603813;9826.950865245628;9854.507274636382;10000.199984001409;9549.427034377959;9633.759156021155;9634.229261659191;10018.39889606626;10060.4988784477;9627.326087173831;10259.84610230862;10284.382937023802;10371.954937574503;10806.729831754268;6768.796936091925;6791.898121528082;7003.684834182576;7213;7538.133114691661;8494.872576911228;10556.208284378548;10594.205434592282;10748.462576871281;10942.945285273523 12:07:36 INFO - PROCESS | 2087 | |3;mootools.html;1254.4605819497613;1360.3946243921453;1384.3078460769614;1436.5905716870686;1655.6026553627125;6420.807375778722;7243.203247642755;7498.325150736433;7504.474686771928;7716.07407111146;7564.54612723237;7583.203763604824;7620.161782203953;7620.6951721931055;7630.618469076552;1960.235508151822;1983.9404817855489;1984.4245168901002;1986.7913869043687;1989.56229629482;1967.0951362373326;1983.6429442700307;1986.6622674145358;2011.0648548424979;2016.5160361513203;1961.9901900490477;1980.9207631694715;1986.0566231040284;1987.3839109875912;1987.4534503011644;1917.6164767046578;1930.1314408516155;1930.449821800794;1934.2746470073723;1935.2355819451238;1886.4623582278984;1889.3292881027312;1903.714442833572;1921.34674210769;1921.567647279351;7064.964675176591;7165.211826699059;7169.533980291264;7173.6413179340825;7244.420446364278;4205.11692544566;4390.560943905616;4393;4401.097774956126;4404.98685302379;2624.3439140214946;2748.3129217695578;2798.3703666675037;2821.238265668258;2823.435312937421;841.6801615386114;850.9574521273912;851.080832700688;858.6179150278128;872.720729366603;10141.44222067786;10258.69223923276;10263.846042309371;10265.897341026568;10282.691519254435;3558.1460449491938;3611.9277614447565;3719.683826874746;3743.2139250757646;3744.344739670547;8135.389845761473;8148.06297275809;8151;8158.551279679675;8159.061707903548;4336.089421221515;4338.739675619409;4339.305711086211;4341.804618792162;4344.283193273138;3529.08243856594;3650.963490365062;3859.2667393195766;4032.072623296658;4049.1294371710223;3115.345777386774;3152.448321543721;3257.8533965971587;3270.4440245157903;3297.3075654112426;947.1570302430843;949.0604301741226;960.3373672166183;963.017721923648;965.0590674092704;660.2374257732326;755.9055118110236;778.2412148155616;784.5684873319651;791.9604019798987;4714.568305792544;4725.621950243973;4989.850304490871;4999.3000979862845;5041.420236672756;814.6782021101632;841.0538144587339;852.5864955496579;882.849915514364;892.2193081054078;3334.54983577214;3373.6795004474534;3434.3303055904357;3457.429524128491;3546.6453354664845;8655.523946182895;9259.907400926038;9262.768430789149;9264.629414823467;9269.90730092704;8911.465312081296;9158.725238242863;9196.908030919605;9204.769880753034;9215.49314787693;6903.585784852825;7089.291070892973;7092.680829362691;7105;7107.111611048515;4877.317175595348;4988.8752781180765;4991.276264941491;5002.074616196015;5033.672811267403;8371.916280837113;8404.159584041547;8450.915490845013;8489.490630562186;8571.95714021426;8189.50862948225;8359.749207523784;8400.075991640793;8637.56812159377;8717.128287171232;6449.96775016122;6541.70562324706;6609.041688955171;6611.504137189634;6671.966640166768;6988.056612357267;7042.084529011196;7051.541649792747;7085.6457177141965;7115.53749006313;6189.442950134509;6342.302346741762;6566.080748695091;6603.339666033358;6666.133402657623;6457.0637257596445;6468.708908099053;6533.67331633407;6537.1174891389055;6552.541322107407;5979.940200597938;6085.726142323695;6086.330503644507;6091.573589848668;6095.299040610386;4036.434899114185;4092.2838503261214;4094.1606970571556;4199.559046300094;4205.3481710334945;4061.1268577256033;4091.2635725569685;4123.175364926967;4269.530351661377;4275.380069889787 12:07:36 INFO - PROCESS | 2087 | |4;prototype.html;277.30673316708214;279.7468291196467;286.49433749432256;289.8941886211531;291.9927001824954;4632.907341853161;4799.13615549201;4801.351817504636;4850.490698476658;4884.7313397763155;4655.44134703836;4706.670533062687;4779.139754844126;4792.952070479294;4807.995003123048;3789.5452545694557;3799.4300854871713;3811.1234416084317;3812.752171108883;3815.1415931415486;3559.003479025877;3810.494854532458;3835.2549589936652;3835.4822099016556;3850.4801851749935;3495.685388315051;3742.4573436851774;3783.81080945953;3787.579657628389;3792.2984247914087;3763.3037887990536;3765.2845959267925;3775.981120094382;3782.1490164712995;3803.220339830328;3309.8250121207107;3323.0861513083855;3330.6669333066743;3337.8998630041137;3343.9832800836084;4532.388127602764;4638.234691275936;4674.813007479696;4760.9049918518585;4763.475358060234;4231.936520952188;4256.446661933929;4258.61672449481;4260;4267.445232119836;4962.503749625044;4964.851054468371;4991.650584459089;5000.7249601271915;5021.799128034911;3502.334556434295;3602.4776407420777;3609.4766258892314;3610.4403817408343;3622.873199438007;5208.166693329049;5211.817586384496;5212.635115541913;5220.9738951305;5249.973750131263;3700.50043244164;3705.7591256568235;3707.9814600926825;3713.4774742355503;3716.7398282120535;3766.3408903441787;3770;3770.0763312988493;3798.772073675588;3807.8286477108595;4172.374143878382;4232.64022558086;4235.788210589458;4293.957060429356;4299.505556860939;4976;4982.77577509013;4989.276554899592;5004.449510553836;5046.21783623539;1234.3211233821362;1254.4919307680404;1289.645347529437;1308.3719814489098;1316.2165266064537;867.8134201146784;911.6809116809196;911.7492689510437;915.2494954137543;923.0677016213539;4474.373587697725;4490.3040028795585;4497.617702495325;4500.244904487253;4515.119551687455;5174.835661976101;5180.818671346485;5182.922256166161;5186.611004174702;5204.42751297357;4376.562343765661;4465.732056076646;4475.5300693426725;4487.371767952489;4512;2721.231199720158;2744.643196384457;2747.842943289548;2750.573661082535;2759.5860620907024;4843.840156233793;4943.629227807929;4946.565496006118;4953.23224900141;4954.777035033432;5122.103631864484;5225.589090945424;5236.711980841091;5238.633295669267;5256.78972841082;4857.708537487762;4869.196582563837;4882.6826256293225;4905.166121759309;4906.950930490721;4868.732219727949;5112.258722485293;5129.179331306973;5141.665791723526;5159.922601160911;4969.776360063878;4970.304157418037;5014.272930425141;5014.5737612303365;5017.297578339108;4833.3958255218095;4875.853724388274;4892.192788189909;4915.213565829593;4932.975335123301;3716.2567486502267;3754.7747135171976;3754.7747135171976;3761.191343861083;3763.9272807249804;4971.577415919687;5224.7126408047925;5256.185291279858;5267.209148889396;5286.867828304323;5056.191009438472;5081.441041485507;5093.974530127326;5108.770105345194;5122.257272695364;4819.783109760139;5111.667741596784;5135.614828887774;5139.331886854685;5147.948520514747;4735.313379559876;4881.8535443936735;4992.350994370854;5008.899822003466;5016.774245159049;4956.083124621957;5114.258432527337;5124.256982737407;5130.230465430215;5134.614903882149;5046.6878611562;5048;5054.772535235997;5057.570106540985;5060.696358218519 12:07:36 INFO - PROCESS | 2087 | |5;yui.html;655.3544758412963;727.7525641281966;736.9963150184249;745.967239272981;746.2798399544439;12870.485180592777;13223.867761322384;13251.072424930248;13271.800922986138;13344.666383340409;13200.53798117066;13709.588712338646;13736.793948090786;14107.717845643081;14220.14679119251;749.4454103963069;766.6128605054467;766.7853001159684;768.6771555946488;768.7271018788333;618.771054709758;635.1901325809583;734.6583838515089;764.9273319034683;768.2317682317682;799.168864381046;803.7628899474669;806.3508875355336;808.7007807111377;809.2716555100427;797.9082405523382;798.078219656298;798.7364169823974;799.2007992007992;799.3684988858795;469.6149157690695;479.29064983823787;583.1951906369226;592.2715060475639;592.9919137466325;12884.484620615163;13340;13447.32763361828;13919.304034798219;13942.51201207963;12486.313252771093;13377.732445351136;13431.731365372638;13462.394192261372;13741.401654869924;10827.83758243627;10868.510917008754;10970.945145274301;11316.660500184926;11348.943255283672;3273.3289675616443;3297.0544183724414;3312.271300313927;3386.373520898617;3388.96611033889;48491.51508484905;49818.25272620914;50354;66126;66413.00380494297;10039.648612298535;10080.344777589433;10086.092251697382;10100.595976160872;10126.645567405105;18504;18508.259669613064;18529.81470185281;18534.351297704514;18545.721814172797;6915.723371065101;6930.8613827724175;6975.511714179958;7149.0706208192605;7243.022192004118;3603.261331427051;3706.4069748840056;3716.1267102230954;3743.6069212732814;3751.962947502338;2892.248015515981;2981.359007813287;3016.3213277012496;3019.667836537979;3029.2578318312153;503.83625321769836;504.5156649616336;509.5745052880812;515.463917525769;515.8839261166207;507.34299082688005;536.2037374498866;601.569877537554;621.5090078837668;625.1185827982523;7637.809054773675;7994.400419968526;8141.51150930946;8241.25828675422;8352.566435204815;532.816178418445;564.5370795947284;575.8042265629622;585.1310803456863;622.1352320274822;3696.31618150643;3723.478712980185;3738.12154143776;3889.9805500972316;3921.627445392683;99472;104322;105049.47475262727;106060.46969765256;106233.46883265534;88147.55926220329;92822.53588732013;94133;94227.52886235525;94491.52754236323;78116;79184;79948;80338.5813780236;80588.59705701434;6770.187577490733;7084.326988936043;7259.673314700745;7264;7322;44738.3289250668;45087.32369014533;45100.548994509634;45314;46129.538704612525;49471;51372;52126;57570.4330810852;59849;10532.57869685204;10809.519305912818;10977.121830253402;11019.779604408028;11120.221584489009;12166.939165304117;12311.69220769488;12361.690957726129;12652.620421387373;12675.556355527513;11251.324920504796;11354.659360219206;11402.429878505876;11756.58851940178;12171.54743170324;11260.774784504429;11634.127440441831;11929.210618407309;12134.575289864812;12216.938915305367;10915.454227288763;11432.599859004895;12395.876041239833;12439.937800310941;12445.128840981406;9346.138351892729;9904.603815847286;9943.353682010646;9963.352382095141;10140.29017968735;9089.63641454361;9220.861687074565;9666.613335466784;10219.489025548544;10278.383297001905 12:07:36 INFO - PROCESS | 2087 | __end_tp_report 12:07:36 INFO - PROCESS | 2087 | __start_cc_report 12:07:36 INFO - PROCESS | 2087 | _x_x_mozilla_cycle_collect,1994 12:07:36 INFO - PROCESS | 2087 | __end_cc_report 12:07:36 INFO - PROCESS | 2087 | __startTimestamp1475608056312__endTimestamp 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | ------- Summary: start ------- 12:07:36 INFO - PROCESS | 2087 | Number of tests: 6 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#0] dojo.html Cycles:165 Average:4504.54 Median:4651.23 stddev:464.57 (10.0%) stddev-sans-first:438.86 12:07:36 INFO - PROCESS | 2087 | Values: 2510.2 2536.3 2568.6 2572.4 2588.0 4472.6 4527.0 4579.1 4596.9 4603.5 4541.2 4574.7 4612.3 4640.8 4668.5 4124.5 4135.5 4191.2 4192.6 4232.0 4235.6 4253.4 4289.7 4292.6 4342.1 4098.1 4224.1 4226.2 4290.3 4315.5 4132.6 4166.9 4183.0 4216.8 4218.4 4186.6 4188.1 4193.5 4243.4 4270.1 4571.2 4573.9 4611.1 4617.0 4644.4 4723.8 4735.8 4763.0 4771.6 4933.0 4804.3 4806.1 4807.5 4836.7 4851.5 4479.9 4484.9 4485.0 4535.1 4540.7 4855.2 4884.1 4884.3 4908.9 4949.2 4797.7 4800.2 4830.3 4855.8 4869.7 4861.6 4880.2 4880.9 4882.7 4934.9 4663.6 4719.1 4832.5 4836.0 4891.0 4750.4 4793.1 4797.2 4803.8 4860.9 4071.3 4075.6 4075.8 4127.7 4130.5 3552.4 3571.8 3575.1 3605.8 3631.6 4181.4 4212.4 4217.8 4266.7 4307.4 4854.3 4881.0 4882.9 4883.6 4939.5 4250.8 4252.2 4257.4 4263.4 4279.7 4256.3 4297.4 4311.4 4333.5 4345.7 4630.5 4651.2 4654.6 4689.5 4709.1 4871.8 4878.6 4881.8 4882.5 4942.5 4531.8 4589.0 4599.0 4619.7 4623.1 4805.0 4825.2 4848.8 4861.2 4862.9 4859.0 4860.0 4865.3 4866.6 4921.0 4622.3 4730.6 4738.1 4740.0 4801.8 4609.5 4696.5 4772.6 4789.8 4849.3 4728.6 4728.7 4736.9 4780.5 4785.6 4669.3 4701.0 4701.4 4728.2 4771.9 4704.3 4715.9 4755.5 4757.1 4776.3 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#1] ext.html Cycles:180 Average:44119.57 Median:35066.15 stddev:77258.90 (220.3%) stddev-sans-first:77406.35 12:07:36 INFO - PROCESS | 2087 | Values: 540.9 555.9 559.9 570.0 585.3 27753.2 37637.6 39023.8 39048.4 43272.9 39128.6 39530.0 39532.2 39537.6 39571.6 7104.4 7512.2 7650.5 7650.9 7651.2 7217.1 7313.5 7322.3 7322.6 9549.8 9584.1 9922.4 9927.6 9938.9 9974.5 9520.9 9555.1 9566.3 9571.1 9587.1 7064.8 7312.6 9339.7 9618.5 9681.0 33876.7 37269.0 37305.0 37320.4 37339.8 2848.2 2900.0 2910.6 2957.1 3234.1 24527.0 24800.3 27167.2 27183.3 27686.9 3370.8 3395.2 3400.8 3458.5 3468.3 243593.0 246018.8 246241.0 246833.8 246844.0 44023.6 45494.8 51143.7 52963.0 53048.2 54406.9 54560.7 54626.5 54655.7 54666.0 14420.5 14586.9 14597.4 14614.7 14721.6 10663.8 10765.7 10770.1 10853.6 10878.3 15371.3 16039.4 16444.0 17265.0 17359.2 6647.5 6749.7 6755.0 6834.7 6838.2 776.3 832.1 847.8 893.1 952.7 24934.3 25977.4 26108.7 26268.2 26391.3 948.1 971.7 1014.8 1055.7 1059.5 12975.5 13943.7 13978.7 14177.4 14211.6 370818.0 373782.0 373815.0 374827.0 375372.0 47124.8 47533.0 47600.0 47637.3 47667.3 1039.9 1093.7 1150.4 1164.2 1204.2 24763.9 28326.2 28391.9 28535.9 28642.6 39198.6 39935.4 39980.4 39984.0 40026.0 242540.0 242809.0 243394.0 243995.0 244030.0 24002.3 24562.4 24761.4 24926.3 25743.1 39190.0 39931.0 40236.0 40261.6 40351.8 34305.5 34483.0 34546.0 34623.0 34651.0 33915.0 34744.3 35016.0 35049.5 35455.3 34537.0 34821.3 35082.8 35418.3 35595.1 29104.6 31191.5 31279.7 31611.0 31755.2 24512.6 24565.6 24633.6 24949.0 24988.5 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#2] jquery.html Cycles:180 Average:23232.72 Median:4564.54 stddev:59115.22 (1295.1%) stddev-sans-first:59256.07 12:07:36 INFO - PROCESS | 2087 | Values: 339.3 344.4 347.6 350.4 373.6 12160.7 15214.7 15770.4 16308.0 17105.1 14109.8 14279.9 14349.1 14401.0 14639.7 3924.9 4977.3 5028.4 5204.9 5207.1 5024.4 5156.1 5171.8 5173.2 5175.0 4909.5 5136.2 5137.5 5137.9 5140.6 5018.4 5045.6 5059.1 5081.9 5092.6 4431.0 4552.7 4557.0 4561.4 4567.7 19287.7 19529.4 19911.7 19991.2 20240.3 1932.4 1953.5 2055.1 2239.3 2323.8 12848.1 12898.4 13019.0 13410.3 13540.6 1581.5 1639.4 1654.7 1748.7 1769.6 61411.0 63743.0 71924.9 71983.0 73004.6 9532.7 9751.2 9776.2 9998.6 10160.9 13751.2 13774.7 13791.4 13798.2 13800.4 6978.0 7189.1 7200.7 7303.7 7313.6 6662.2 6782.9 6873.6 6912.5 6923.1 4107.3 4156.9 4201.8 4328.8 4381.8 1766.5 1777.1 1779.6 1813.1 1827.3 793.3 804.8 846.4 857.5 885.1 15673.6 16917.4 17000.8 17214.9 17287.1 831.4 840.8 846.6 847.4 876.5 8814.3 8949.7 9411.7 9430.0 9562.7 325002.0 334818.0 372351.0 372576.0 373047.0 75044.0 76067.0 76539.0 78590.0 79056.6 894.3 898.5 902.0 908.6 918.3 14682.4 14720.2 15167.1 15276.4 15593.9 36861.6 38602.6 38894.0 39610.6 40195.4 51575.0 52269.7 52429.7 52495.5 53308.2 7261.2 7345.6 7392.6 7456.1 7566.0 8488.6 10890.3 11231.2 11446.4 11600.6 9411.4 9610.4 9827.0 9854.5 10000.2 9549.4 9633.8 9634.2 10018.4 10060.5 9627.3 10259.8 10284.4 10372.0 10806.7 6768.8 6791.9 7003.7 7213.0 7538.1 8494.9 10556.2 10594.2 10748.5 10942.9 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#3] mootools.html Cycles:180 Average:4827.26 Median:6032.83 stddev:2757.30 (45.7%) stddev-sans-first:2751.97 12:07:36 INFO - PROCESS | 2087 | Values: 1254.5 1360.4 1384.3 1436.6 1655.6 6420.8 7243.2 7498.3 7504.5 7716.1 7564.5 7583.2 7620.2 7620.7 7630.6 1960.2 1983.9 1984.4 1986.8 1989.6 1967.1 1983.6 1986.7 2011.1 2016.5 1962.0 1980.9 1986.1 1987.4 1987.5 1917.6 1930.1 1930.4 1934.3 1935.2 1886.5 1889.3 1903.7 1921.3 1921.6 7065.0 7165.2 7169.5 7173.6 7244.4 4205.1 4390.6 4393.0 4401.1 4405.0 2624.3 2748.3 2798.4 2821.2 2823.4 841.7 851.0 851.1 858.6 872.7 10141.4 10258.7 10263.8 10265.9 10282.7 3558.1 3611.9 3719.7 3743.2 3744.3 8135.4 8148.1 8151.0 8158.6 8159.1 4336.1 4338.7 4339.3 4341.8 4344.3 3529.1 3651.0 3859.3 4032.1 4049.1 3115.3 3152.4 3257.9 3270.4 3297.3 947.2 949.1 960.3 963.0 965.1 660.2 755.9 778.2 784.6 792.0 4714.6 4725.6 4989.9 4999.3 5041.4 814.7 841.1 852.6 882.8 892.2 3334.5 3373.7 3434.3 3457.4 3546.6 8655.5 9259.9 9262.8 9264.6 9269.9 8911.5 9158.7 9196.9 9204.8 9215.5 6903.6 7089.3 7092.7 7105.0 7107.1 4877.3 4988.9 4991.3 5002.1 5033.7 8371.9 8404.2 8450.9 8489.5 8572.0 8189.5 8359.7 8400.1 8637.6 8717.1 6450.0 6541.7 6609.0 6611.5 6672.0 6988.1 7042.1 7051.5 7085.6 7115.5 6189.4 6342.3 6566.1 6603.3 6666.1 6457.1 6468.7 6533.7 6537.1 6552.5 5979.9 6085.7 6086.3 6091.6 6095.3 4036.4 4092.3 4094.2 4199.6 4205.3 4061.1 4091.3 4123.2 4269.5 4275.4 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#4] prototype.html Cycles:180 Average:4193.49 Median:4796.04 stddev:1208.34 (25.2%) stddev-sans-first:1175.44 12:07:36 INFO - PROCESS | 2087 | Values: 277.3 279.7 286.5 289.9 292.0 4632.9 4799.1 4801.4 4850.5 4884.7 4655.4 4706.7 4779.1 4793.0 4808.0 3789.5 3799.4 3811.1 3812.8 3815.1 3559.0 3810.5 3835.3 3835.5 3850.5 3495.7 3742.5 3783.8 3787.6 3792.3 3763.3 3765.3 3776.0 3782.1 3803.2 3309.8 3323.1 3330.7 3337.9 3344.0 4532.4 4638.2 4674.8 4760.9 4763.5 4231.9 4256.4 4258.6 4260.0 4267.4 4962.5 4964.9 4991.7 5000.7 5021.8 3502.3 3602.5 3609.5 3610.4 3622.9 5208.2 5211.8 5212.6 5221.0 5250.0 3700.5 3705.8 3708.0 3713.5 3716.7 3766.3 3770.0 3770.1 3798.8 3807.8 4172.4 4232.6 4235.8 4294.0 4299.5 4976.0 4982.8 4989.3 5004.4 5046.2 1234.3 1254.5 1289.6 1308.4 1316.2 867.8 911.7 911.7 915.2 923.1 4474.4 4490.3 4497.6 4500.2 4515.1 5174.8 5180.8 5182.9 5186.6 5204.4 4376.6 4465.7 4475.5 4487.4 4512.0 2721.2 2744.6 2747.8 2750.6 2759.6 4843.8 4943.6 4946.6 4953.2 4954.8 5122.1 5225.6 5236.7 5238.6 5256.8 4857.7 4869.2 4882.7 4905.2 4907.0 4868.7 5112.3 5129.2 5141.7 5159.9 4969.8 4970.3 5014.3 5014.6 5017.3 4833.4 4875.9 4892.2 4915.2 4933.0 3716.3 3754.8 3754.8 3761.2 3763.9 4971.6 5224.7 5256.2 5267.2 5286.9 5056.2 5081.4 5094.0 5108.8 5122.3 4819.8 5111.7 5135.6 5139.3 5147.9 4735.3 4881.9 4992.4 5008.9 5016.8 4956.1 5114.3 5124.3 5130.2 5134.6 5046.7 5048.0 5054.8 5057.6 5060.7 12:07:36 INFO - PROCESS | 2087 | 12:07:36 INFO - PROCESS | 2087 | [#5] yui.html Cycles:180 Average:17944.33 Median:45721.77 stddev:26342.76 (57.6%) stddev-sans-first:26384.67 12:07:36 INFO - PROCESS | 2087 | Values: 655.4 727.8 737.0 746.0 746.3 12870.5 13223.9 13251.1 13271.8 13344.7 13200.5 13709.6 13736.8 14107.7 14220.1 749.4 766.6 766.8 768.7 768.7 618.8 635.2 734.7 764.9 768.2 799.2 803.8 806.4 808.7 809.3 797.9 798.1 798.7 799.2 799.4 469.6 479.3 583.2 592.3 593.0 12884.5 13340.0 13447.3 13919.3 13942.5 12486.3 13377.7 13431.7 13462.4 13741.4 10827.8 10868.5 10970.9 11316.7 11348.9 3273.3 3297.1 3312.3 3386.4 3389.0 48491.5 49818.3 50354.0 66126.0 66413.0 10039.6 10080.3 10086.1 10100.6 10126.6 18504.0 18508.3 18529.8 18534.4 18545.7 6915.7 6930.9 6975.5 7149.1 7243.0 3603.3 3706.4 3716.1 3743.6 3752.0 2892.2 2981.4 3016.3 3019.7 3029.3 503.8 504.5 509.6 515.5 515.9 507.3 536.2 601.6 621.5 625.1 7637.8 7994.4 8141.5 8241.3 8352.6 532.8 564.5 575.8 585.1 622.1 3696.3 3723.5 3738.1 3890.0 3921.6 99472.0 104322.0 105049.5 106060.5 106233.5 88147.6 92822.5 94133.0 94227.5 94491.5 78116.0 79184.0 79948.0 80338.6 80588.6 6770.2 7084.3 7259.7 7264.0 7322.0 44738.3 45087.3 45100.5 45314.0 46129.5 49471.0 51372.0 52126.0 57570.4 59849.0 10532.6 10809.5 10977.1 11019.8 11120.2 12166.9 12311.7 12361.7 12652.6 12675.6 11251.3 11354.7 11402.4 11756.6 12171.5 11260.8 11634.1 11929.2 12134.6 12216.9 10915.5 11432.6 12395.9 12439.9 12445.1 9346.1 9904.6 9943.4 9963.4 10140.3 9089.6 9220.9 9666.6 10219.5 10278.4 12:07:36 INFO - PROCESS | 2087 | -------- Summary: end -------- 12:07:36 INFO - PROCESS | 2087 | 12:07:37 INFO - TEST-INFO | 2087: exit 0 12:07:37 INFO - TEST-OK | dromaeo_css | took 1122146ms 12:07:37 INFO - TEST-START | kraken 12:07:37 INFO - operating with platform_type : linux_ 12:07:37 INFO - Initialising browser for kraken test... 12:07:37 INFO - TEST-INFO | started process 2468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4UIaR8/profile http://localhost:34257/getInfo.html) 12:07:42 INFO - PROCESS | 2468 | __metrics Screen width/height:1600/1200 12:07:42 INFO - PROCESS | 2468 | colorDepth:24 12:07:42 INFO - PROCESS | 2468 | Browser inner width/height: 1024/697 12:07:42 INFO - PROCESS | 2468 | __metrics 12:07:42 INFO - PROCESS | 2468 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:07:42 INFO - PROCESS | 2468 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:07:43 INFO - PROCESS | 2468 | console.error: PushService: 12:07:43 INFO - PROCESS | 2468 | stateChangeProcessEnqueue: Error transitioning state 12:07:43 INFO - PROCESS | 2468 | UnknownError 12:07:44 INFO - TEST-INFO | 2468: exit 0 12:07:44 INFO - Browser initialized. 12:07:44 INFO - Running cycle 1/1 for kraken test... 12:07:44 INFO - TEST-INFO | started process 2567 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4UIaR8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:56 INFO - PROCESS | 2567 | RSS: Main: 175144960 12:07:56 INFO - PROCESS | 2567 | 12:07:59 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34257/tests/kraken/driver.html?testName=audio-beat-detection) 12:08:00 INFO - PROCESS | 2567 | RSS: Main: 242737152 12:08:00 INFO - PROCESS | 2567 | 12:08:02 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34257/tests/kraken/driver.html?testName=audio-dft) 12:08:03 INFO - PROCESS | 2567 | RSS: Main: 219537408 12:08:03 INFO - PROCESS | 2567 | 12:08:06 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34257/tests/kraken/driver.html?testName=audio-fft) 12:08:07 INFO - PROCESS | 2567 | RSS: Main: 300716032 12:08:07 INFO - PROCESS | 2567 | 12:08:09 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34257/tests/kraken/driver.html?testName=audio-oscillator) 12:08:10 INFO - PROCESS | 2567 | RSS: Main: 212029440 12:08:10 INFO - PROCESS | 2567 | 12:08:12 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34257/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:08:13 INFO - PROCESS | 2567 | RSS: Main: 214032384 12:08:13 INFO - PROCESS | 2567 | 12:08:16 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34257/tests/kraken/driver.html?testName=imaging-darkroom) 12:08:16 INFO - PROCESS | 2567 | RSS: Main: 251146240 12:08:16 INFO - PROCESS | 2567 | 12:08:20 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34257/tests/kraken/driver.html?testName=imaging-desaturate) 12:08:21 INFO - PROCESS | 2567 | RSS: Main: 250703872 12:08:21 INFO - PROCESS | 2567 | 12:08:23 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34257/tests/kraken/driver.html?testName=json-parse-financial) 12:08:24 INFO - PROCESS | 2567 | RSS: Main: 249708544 12:08:24 INFO - PROCESS | 2567 | 12:08:26 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34257/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:08:26 INFO - PROCESS | 2567 | RSS: Main: 211918848 12:08:26 INFO - PROCESS | 2567 | 12:08:28 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:08:29 INFO - PROCESS | 2567 | RSS: Main: 219742208 12:08:29 INFO - PROCESS | 2567 | 12:08:31 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:08:32 INFO - PROCESS | 2567 | RSS: Main: 227532800 12:08:32 INFO - PROCESS | 2567 | 12:08:35 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:08:35 INFO - PROCESS | 2567 | RSS: Main: 236642304 12:08:35 INFO - PROCESS | 2567 | 12:08:38 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:08:39 INFO - PROCESS | 2567 | RSS: Main: 233193472 12:08:39 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:34257/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34257/tests/kraken/driver.html?testName=ai-astar) 12:08:41 INFO - PROCESS | 2567 | RSS: Main: 283693056 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | __start_tp_report 12:08:41 INFO - PROCESS | 2567 | _x_x_mozilla_page_load 12:08:41 INFO - PROCESS | 2567 | _x_x_mozilla_page_load_details 12:08:41 INFO - PROCESS | 2567 | |i|pagename|runs| 12:08:41 INFO - PROCESS | 2567 | |0;ai-astar;112;113;116;113;114;115;115;113;114;113 12:08:41 INFO - PROCESS | 2567 | |1;audio-beat-detection;150;135;130;175;127;129;134;126;167;132 12:08:41 INFO - PROCESS | 2567 | |2;audio-dft;162;162;161;162;163;163;163;163;161;161 12:08:41 INFO - PROCESS | 2567 | |3;audio-fft;76;76;77;115;78;78;77;76;104;77 12:08:41 INFO - PROCESS | 2567 | |4;audio-oscillator;85;84;115;86;84;84;111;84;85;119 12:08:41 INFO - PROCESS | 2567 | |5;imaging-gaussian-blur;117;117;118;116;117;118;118;117;121;118 12:08:41 INFO - PROCESS | 2567 | |6;imaging-darkroom;147;147;146;147;146;146;146;147;145;147 12:08:41 INFO - PROCESS | 2567 | |7;imaging-desaturate;95;94;95;94;94;95;95;95;95;95 12:08:41 INFO - PROCESS | 2567 | |8;json-parse-financial;67;65;64;65;65;65;65;65;65;64 12:08:41 INFO - PROCESS | 2567 | |9;json-stringify-tinderbox;65;65;66;66;67;65;66;66;66;65 12:08:41 INFO - PROCESS | 2567 | |10;stanford-crypto-aes;84;83;84;93;86;84;83;87;83;83 12:08:41 INFO - PROCESS | 2567 | |11;stanford-crypto-ccm;140;164;146;137;138;140;140;139;183;163 12:08:41 INFO - PROCESS | 2567 | |12;stanford-crypto-pbkdf2;180;177;177;175;176;176;174;173;172;172 12:08:41 INFO - PROCESS | 2567 | |13;stanford-crypto-sha256-iterative;71;70;70;70;71;71;70;70;73;71 12:08:41 INFO - PROCESS | 2567 | __end_tp_report 12:08:41 INFO - PROCESS | 2567 | __start_cc_report 12:08:41 INFO - PROCESS | 2567 | _x_x_mozilla_cycle_collect,1944 12:08:41 INFO - PROCESS | 2567 | __end_cc_report 12:08:41 INFO - PROCESS | 2567 | __startTimestamp1475608121470__endTimestamp 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | ------- Summary: start ------- 12:08:41 INFO - PROCESS | 2567 | Number of tests: 14 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.12 12:08:41 INFO - PROCESS | 2567 | Values: 112.0 113.0 116.0 113.0 114.0 115.0 115.0 113.0 114.0 113.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:134.50 stddev:17.52 (13.0%) stddev-sans-first:18.24 12:08:41 INFO - PROCESS | 2567 | Values: 150.0 135.0 130.0 175.0 127.0 129.0 134.0 126.0 167.0 132.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:0.88 (0.5%) stddev-sans-first:0.93 12:08:41 INFO - PROCESS | 2567 | Values: 162.0 162.0 161.0 162.0 163.0 163.0 163.0 163.0 161.0 161.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#3] audio-fft Cycles:10 Average:83.40 Median:77.00 stddev:14.02 (18.2%) stddev-sans-first:14.61 12:08:41 INFO - PROCESS | 2567 | Values: 76.0 76.0 77.0 115.0 78.0 78.0 77.0 76.0 104.0 77.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:84.00 stddev:14.83 (17.7%) stddev-sans-first:15.39 12:08:41 INFO - PROCESS | 2567 | Values: 85.0 84.0 115.0 86.0 84.0 84.0 111.0 84.0 85.0 119.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:1.34 (1.1%) stddev-sans-first:1.39 12:08:41 INFO - PROCESS | 2567 | Values: 117.0 117.0 118.0 116.0 117.0 118.0 118.0 117.0 121.0 118.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 12:08:41 INFO - PROCESS | 2567 | Values: 147.0 147.0 146.0 147.0 146.0 146.0 146.0 147.0 145.0 147.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 12:08:41 INFO - PROCESS | 2567 | Values: 95.0 94.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.44 12:08:41 INFO - PROCESS | 2567 | Values: 67.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 64.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:08:41 INFO - PROCESS | 2567 | Values: 65.0 65.0 66.0 66.0 67.0 65.0 66.0 66.0 66.0 65.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:84.00 stddev:3.13 (3.7%) stddev-sans-first:3.30 12:08:41 INFO - PROCESS | 2567 | Values: 84.0 83.0 84.0 93.0 86.0 84.0 83.0 87.0 83.0 83.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:143.00 stddev:15.61 (10.9%) stddev-sans-first:16.22 12:08:41 INFO - PROCESS | 2567 | Values: 140.0 164.0 146.0 137.0 138.0 140.0 140.0 139.0 183.0 163.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:176.00 stddev:2.53 (1.4%) stddev-sans-first:2.00 12:08:41 INFO - PROCESS | 2567 | Values: 180.0 177.0 177.0 175.0 176.0 176.0 174.0 173.0 172.0 172.0 12:08:41 INFO - PROCESS | 2567 | 12:08:41 INFO - PROCESS | 2567 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:0.95 (1.3%) stddev-sans-first:1.00 12:08:41 INFO - PROCESS | 2567 | Values: 71.0 70.0 70.0 70.0 71.0 71.0 70.0 70.0 73.0 71.0 12:08:41 INFO - PROCESS | 2567 | -------- Summary: end -------- 12:08:41 INFO - PROCESS | 2567 | 12:08:42 INFO - TEST-INFO | 2567: exit 0 12:08:42 INFO - TEST-OK | kraken | took 65435ms 12:08:42 INFO - SUITE-END | took 1187s 12:08:43 INFO - Completed test suite (00:19:47) 12:08:43 INFO - javascript benchmark 12:08:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2510.1967370441453, 2536.3278731136243, 2568.601866710659, 2572.356910772307, 2588.0424243030084, 4472.642188624902, 4527.049319642871, 4579.12996530659, 4596.931046034304, 4603.493615702274, 4541.1598854212, 4574.725516469014, 4612.33121197427, 4640.791164397602, 4668.5098064703225, 4124.505059392877, 4135.503739551258, 4191.203671302443, 4192.643625291854, 4232.0477892474255, 4235.576442355755, 4253.44705188325, 4289.6997210195295, 4292.570742925714, 4342.066455712022, 4098.139390727951, 4224.134052519226, 4226.218149642326, 4290.2706539888295, 4315.4821421429315, 4132.607402296807, 4166.895827604304, 4183.037901282722, 4216.7680777557225, 4218.388333691628, 4186.623203911632, 4188.120494696117, 4193.454850869368, 4243.384709217177, 4270.0605866709275, 4571.200039993021, 4573.931391029138, 4611.100835337111, 4616.976915115437, 4644.373009643722, 4723.811047558093, 4735.786889589976, 4762.976185119087, 4771.594414474739, 4933.01339732055, 4804.303376010459, 4806.134895718769, 4807.543283388072, 4836.733979631119, 4851.539103785135, 4479.910401791978, 4484.865454036384, 4485.0, 4535.138323718483, 4540.682152249311, 4855.247436647325, 4884.145274576935, 4884.267359895973, 4908.901821963541, 4949.232868905326, 4797.736124513115, 4800.159972004955, 4830.29960655703, 4855.757212139379, 4869.732164730974, 4861.6353773467135, 4880.219164933594, 4880.853574392774, 4882.707037577757, 4934.8766280843265, 4663.603593694506, 4719.079779442975, 4832.492588278251, 4835.975820120877, 4890.975545122323, 4750.3824502815105, 4793.113274044313, 4797.18447863864, 4803.759812009455, 4860.854174374775, 4071.2875246831686, 4075.5720649331392, 4075.8369665213654, 4127.690423218271, 4130.463039804867, 3552.431610942237, 3571.8392672329805, 3575.0883524701553, 3605.7656252344036, 3631.6368363164, 4181.393697913785, 4212.389203565466, 4217.7891105444605, 4266.679999000087, 4307.353896915488, 4854.271859221145, 4881.0, 4882.853514394574, 4883.58489528387, 4939.53074457926, 4250.808713607833, 4252.234597772431, 4257.361395790656, 4263.381809637585, 4279.679024073207, 4256.3189889617515, 4297.376880352332, 4311.417958575615, 4333.479982402069, 4345.695801293942, 4630.467496237976, 4651.232546629768, 4654.627629789677, 4689.484156742756, 4709.058188362273, 4871.780769865293, 4878.56092951636, 4881.829135980366, 4882.511748825089, 4942.481039490802, 4531.750753708578, 4589.0363023765385, 4598.954010459986, 4619.653525985497, 4623.098495793288, 4805.038992201504, 4825.227963525959, 4848.806047758191, 4861.222204447272, 4862.902741945212, 4858.975705121452, 4859.975700121477, 4865.343178670976, 4866.610671146371, 4921.015796840575, 4622.329762184397, 4730.574248317668, 4738.147133516, 4740.028294199749, 4801.831935882227, 4609.492955774795, 4696.4599071105895, 4772.642051846056, 4789.832355867528, 4849.3210950467665, 4728.62171026324, 4728.739919304471, 4736.881577960578, 4780.545848144421, 4785.641076919175, 4669.322948172564, 4700.952990470051, 4701.412323459567, 4728.219843725747, 4771.880702982315, 4704.341392205162, 4715.882102947454, 4755.548222918817, 4757.143714131351, 4776.307435421914], "value": 4475.153914043847, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.878302381964, 555.9193916882052, 559.8796258804356, 569.9943000569996, 585.3268740947907, 27753.16740497786, 37637.62362376376, 39023.80488097563, 39048.414273785915, 43272.91817704551, 39128.60871391285, 39530.0, 39532.20935581287, 39537.60462395375, 39571.60428395715, 7104.396126329269, 7512.248775122499, 7650.464467487278, 7650.885236721454, 7651.23487651236, 7217.133943926736, 7313.524620899624, 7322.340989310961, 7322.633868306563, 9549.761255968588, 9584.089511496397, 9922.355046921963, 9927.553260103312, 9938.90061099387, 9974.501274936261, 9520.8571871422, 9555.092266234731, 9566.282528810367, 9571.138597526186, 9587.137157655774, 7064.823379415556, 7312.634368281618, 9339.673111441067, 9618.470984095871, 9680.95159524205, 33876.6612333876, 37269.0, 37305.0, 37320.440193397124, 37339.813300933325, 2848.2167403963867, 2899.9565006524917, 2910.63617047869, 2957.07789936543, 3234.110619579611, 24527.0, 24800.25599232026, 27167.184984450498, 27183.320416989536, 27686.861565692245, 3370.8314584270934, 3395.2190996070794, 3400.7619466637343, 3458.515807786912, 3468.271662950783, 243593.0, 246018.7699061511, 246241.0, 246833.76583117148, 246844.0, 44023.559764402584, 45494.77252613782, 51143.7442812791, 52963.0, 53048.204276935874, 54406.927099476816, 54560.72719636376, 54626.45373546293, 54655.72672136693, 54666.0, 14420.495282665057, 14586.927065364605, 14597.41610335596, 14614.707705845824, 14721.55835324942, 10663.84004239937, 10765.730856728487, 10770.138388928866, 10853.620123295805, 10878.292910960763, 15371.308291126703, 16039.438619648256, 16444.0, 17265.0, 17359.21883515245, 6647.471081651247, 6749.662516874137, 6755.0, 6834.6582670866255, 6838.247792742794, 776.3013288040809, 832.142892820396, 847.8431490174338, 893.1024320557798, 952.6665667016489, 24934.251972440492, 25977.382875605817, 26108.73891261101, 26268.21195364142, 26391.340216494358, 948.0756262644005, 971.7133445633414, 1014.8224060789332, 1055.730788648905, 1059.523214553454, 12975.545855894998, 13943.65140871466, 13978.720425591431, 14177.362018709182, 14211.64470888236, 370818.0, 373782.0, 373815.0, 374827.0, 375372.0, 47124.76437617859, 47533.0, 47600.0, 47637.28544071842, 47667.284990725166, 1039.9064084232455, 1093.7320356512705, 1150.3500522204927, 1164.1618035014944, 1204.2232759869835, 24763.876180618983, 28326.15021549357, 28391.86432542675, 28535.857320714094, 28642.57036144501, 39198.6080139195, 39935.40096898607, 39980.400293996194, 39984.0, 40026.0, 242540.0, 242809.0, 243394.0, 243995.0, 244030.0, 24002.27993160208, 24562.385940351633, 24761.380965476008, 24926.252212433657, 25743.069269805215, 39190.0, 39931.0, 40236.0, 40261.59738402579, 40351.798241008604, 34305.48541771826, 34483.0, 34546.0, 34623.0, 34651.0, 33915.0, 34744.30511389809, 35016.0, 35049.47425788564, 35455.290894182486, 34537.0, 34821.30357392889, 35082.82458587691, 35418.29163416769, 35595.11012224715, 29104.56343154812, 31191.532127017657, 31279.687203127676, 31611.0, 31755.20611984719, 24512.632310515, 24565.631515526922, 24633.63049554294, 24949.0, 24988.50022999566], "value": 17094.70383978471, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.2858033838768, 344.4110570923721, 347.6193568042993, 350.4043126684635, 373.6319725070805, 12160.695982600428, 15214.695706085886, 15770.369185232606, 16308.021518708885, 17105.144742762845, 14109.788353174685, 14279.857201428009, 14349.139051656883, 14401.0, 14639.707205855902, 3924.862629807957, 4977.278294647274, 5028.4340104274415, 5204.9219261711105, 5207.06272870885, 5024.371953505812, 5156.071907056728, 5171.767270472819, 5173.224016397533, 5175.042617115827, 4909.459959404463, 5136.178211486163, 5137.460566640505, 5137.871553211181, 5140.563052140573, 5018.372703412056, 5045.596352291826, 5059.139946209153, 5081.872953176163, 5092.567131793802, 4430.977845110754, 4552.704074235164, 4557.0430209656015, 4561.384213131218, 4567.703099298568, 19287.710684339745, 19529.414117576496, 19911.701324480146, 19991.200351985903, 20240.291589794433, 1932.420273917819, 1953.4823271832975, 2055.095757866534, 2239.3393948785147, 2323.814094872423, 12848.100632955697, 12898.419571119322, 13019.0, 13410.26243556604, 13540.59378218655, 1581.533447632951, 1639.426200829712, 1654.7269700499403, 1748.7376893465955, 1769.5841477252836, 61411.0, 63743.0, 71924.92112618368, 71983.0, 73004.63497682531, 9532.666356677482, 9751.21990240779, 9776.217902567778, 9998.600055997678, 10160.949195253976, 13751.17492950406, 13774.724505510034, 13791.448342066406, 13798.241096739575, 13800.447982080605, 6977.976386847758, 7189.137303523611, 7200.675969581382, 7303.671334789948, 7313.561186328731, 6662.200535935719, 6782.9321706783285, 6873.559886240313, 6912.481563882729, 6923.134608173978, 4107.322291821816, 4156.896077598024, 4201.789910504463, 4328.783560821947, 4381.802818873159, 1766.5053784940237, 1777.129206688739, 1779.6173822628198, 1813.0934532733634, 1827.2782251010776, 793.2741541489472, 804.7706403674924, 846.3694547562105, 857.5455008845322, 885.0521230361045, 15673.608159796097, 16917.407890723767, 17000.82999170017, 17214.913925430545, 17287.13564321779, 831.4470876866936, 840.785599672092, 846.6317152038882, 847.3814115695577, 876.5179151466668, 8814.338924580683, 8949.657551367347, 9411.6705915294, 9429.952850235704, 9562.665306714232, 325002.0, 334818.0, 372351.0, 372576.0, 373047.0, 75044.0, 76067.0, 76539.0, 78590.0, 79056.60471697604, 894.2622336572354, 898.5462341517492, 902.0, 908.5911339897073, 918.2975024106564, 14682.412703492168, 14720.19038952868, 15167.084836913393, 15276.388944442542, 15593.922030389775, 36861.63138368582, 38602.61397386102, 38894.0, 39610.60389396069, 40195.3970690434, 51575.0, 52269.7386513065, 52429.7378513105, 52495.475045249055, 53308.2003769936, 7261.237570055279, 7345.641056404582, 7392.593407362647, 7456.142543607338, 7565.962170189334, 8488.618012189343, 10890.29213101146, 11231.213815033196, 11446.370449625017, 11600.593979210687, 9411.43531388119, 9610.375325603813, 9826.950865245628, 9854.507274636382, 10000.199984001409, 9549.427034377959, 9633.759156021155, 9634.229261659191, 10018.39889606626, 10060.4988784477, 9627.326087173831, 10259.84610230862, 10284.382937023802, 10371.954937574503, 10806.729831754268, 6768.796936091925, 6791.898121528082, 7003.684834182576, 7213.0, 7538.133114691661, 8494.872576911228, 10556.208284378548, 10594.205434592282, 10748.462576871281, 10942.945285273523], "value": 8011.460577721013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1254.4605819497613, 1360.3946243921453, 1384.3078460769614, 1436.5905716870686, 1655.6026553627125, 6420.807375778722, 7243.203247642755, 7498.325150736433, 7504.474686771928, 7716.07407111146, 7564.54612723237, 7583.203763604824, 7620.161782203953, 7620.6951721931055, 7630.618469076552, 1960.235508151822, 1983.9404817855489, 1984.4245168901002, 1986.7913869043687, 1989.56229629482, 1967.0951362373326, 1983.6429442700307, 1986.6622674145358, 2011.0648548424979, 2016.5160361513203, 1961.9901900490477, 1980.9207631694715, 1986.0566231040284, 1987.3839109875912, 1987.4534503011644, 1917.6164767046578, 1930.1314408516155, 1930.449821800794, 1934.2746470073723, 1935.2355819451238, 1886.4623582278984, 1889.3292881027312, 1903.714442833572, 1921.34674210769, 1921.567647279351, 7064.964675176591, 7165.211826699059, 7169.533980291264, 7173.6413179340825, 7244.420446364278, 4205.11692544566, 4390.560943905616, 4393.0, 4401.097774956126, 4404.98685302379, 2624.3439140214946, 2748.3129217695578, 2798.3703666675037, 2821.238265668258, 2823.435312937421, 841.6801615386114, 850.9574521273912, 851.080832700688, 858.6179150278128, 872.720729366603, 10141.44222067786, 10258.69223923276, 10263.846042309371, 10265.897341026568, 10282.691519254435, 3558.1460449491938, 3611.9277614447565, 3719.683826874746, 3743.2139250757646, 3744.344739670547, 8135.389845761473, 8148.06297275809, 8151.0, 8158.551279679675, 8159.061707903548, 4336.089421221515, 4338.739675619409, 4339.305711086211, 4341.804618792162, 4344.283193273138, 3529.08243856594, 3650.963490365062, 3859.2667393195766, 4032.072623296658, 4049.1294371710223, 3115.345777386774, 3152.448321543721, 3257.8533965971587, 3270.4440245157903, 3297.3075654112426, 947.1570302430843, 949.0604301741226, 960.3373672166183, 963.017721923648, 965.0590674092704, 660.2374257732326, 755.9055118110236, 778.2412148155616, 784.5684873319651, 791.9604019798987, 4714.568305792544, 4725.621950243973, 4989.850304490871, 4999.3000979862845, 5041.420236672756, 814.6782021101632, 841.0538144587339, 852.5864955496579, 882.849915514364, 892.2193081054078, 3334.54983577214, 3373.6795004474534, 3434.3303055904357, 3457.429524128491, 3546.6453354664845, 8655.523946182895, 9259.907400926038, 9262.768430789149, 9264.629414823467, 9269.90730092704, 8911.465312081296, 9158.725238242863, 9196.908030919605, 9204.769880753034, 9215.49314787693, 6903.585784852825, 7089.291070892973, 7092.680829362691, 7105.0, 7107.111611048515, 4877.317175595348, 4988.8752781180765, 4991.276264941491, 5002.074616196015, 5033.672811267403, 8371.916280837113, 8404.159584041547, 8450.915490845013, 8489.490630562186, 8571.95714021426, 8189.50862948225, 8359.749207523784, 8400.075991640793, 8637.56812159377, 8717.128287171232, 6449.96775016122, 6541.70562324706, 6609.041688955171, 6611.504137189634, 6671.966640166768, 6988.056612357267, 7042.084529011196, 7051.541649792747, 7085.6457177141965, 7115.53749006313, 6189.442950134509, 6342.302346741762, 6566.080748695091, 6603.339666033358, 6666.133402657623, 6457.0637257596445, 6468.708908099053, 6533.67331633407, 6537.1174891389055, 6552.541322107407, 5979.940200597938, 6085.726142323695, 6086.330503644507, 6091.573589848668, 6095.299040610386, 4036.434899114185, 4092.2838503261214, 4094.1606970571556, 4199.559046300094, 4205.3481710334945, 4061.1268577256033, 4091.2635725569685, 4123.175364926967, 4269.530351661377, 4275.380069889787], "value": 3852.0749873925847, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.30673316708214, 279.7468291196467, 286.49433749432256, 289.8941886211531, 291.9927001824954, 4632.907341853161, 4799.13615549201, 4801.351817504636, 4850.490698476658, 4884.7313397763155, 4655.44134703836, 4706.670533062687, 4779.139754844126, 4792.952070479294, 4807.995003123048, 3789.5452545694557, 3799.4300854871713, 3811.1234416084317, 3812.752171108883, 3815.1415931415486, 3559.003479025877, 3810.494854532458, 3835.2549589936652, 3835.4822099016556, 3850.4801851749935, 3495.685388315051, 3742.4573436851774, 3783.81080945953, 3787.579657628389, 3792.2984247914087, 3763.3037887990536, 3765.2845959267925, 3775.981120094382, 3782.1490164712995, 3803.220339830328, 3309.8250121207107, 3323.0861513083855, 3330.6669333066743, 3337.8998630041137, 3343.9832800836084, 4532.388127602764, 4638.234691275936, 4674.813007479696, 4760.9049918518585, 4763.475358060234, 4231.936520952188, 4256.446661933929, 4258.61672449481, 4260.0, 4267.445232119836, 4962.503749625044, 4964.851054468371, 4991.650584459089, 5000.7249601271915, 5021.799128034911, 3502.334556434295, 3602.4776407420777, 3609.4766258892314, 3610.4403817408343, 3622.873199438007, 5208.166693329049, 5211.817586384496, 5212.635115541913, 5220.9738951305, 5249.973750131263, 3700.50043244164, 3705.7591256568235, 3707.9814600926825, 3713.4774742355503, 3716.7398282120535, 3766.3408903441787, 3770.0, 3770.0763312988493, 3798.772073675588, 3807.8286477108595, 4172.374143878382, 4232.64022558086, 4235.788210589458, 4293.957060429356, 4299.505556860939, 4976.0, 4982.77577509013, 4989.276554899592, 5004.449510553836, 5046.21783623539, 1234.3211233821362, 1254.4919307680404, 1289.645347529437, 1308.3719814489098, 1316.2165266064537, 867.8134201146784, 911.6809116809196, 911.7492689510437, 915.2494954137543, 923.0677016213539, 4474.373587697725, 4490.3040028795585, 4497.617702495325, 4500.244904487253, 4515.119551687455, 5174.835661976101, 5180.818671346485, 5182.922256166161, 5186.611004174702, 5204.42751297357, 4376.562343765661, 4465.732056076646, 4475.5300693426725, 4487.371767952489, 4512.0, 2721.231199720158, 2744.643196384457, 2747.842943289548, 2750.573661082535, 2759.5860620907024, 4843.840156233793, 4943.629227807929, 4946.565496006118, 4953.23224900141, 4954.777035033432, 5122.103631864484, 5225.589090945424, 5236.711980841091, 5238.633295669267, 5256.78972841082, 4857.708537487762, 4869.196582563837, 4882.6826256293225, 4905.166121759309, 4906.950930490721, 4868.732219727949, 5112.258722485293, 5129.179331306973, 5141.665791723526, 5159.922601160911, 4969.776360063878, 4970.304157418037, 5014.272930425141, 5014.5737612303365, 5017.297578339108, 4833.3958255218095, 4875.853724388274, 4892.192788189909, 4915.213565829593, 4932.975335123301, 3716.2567486502267, 3754.7747135171976, 3754.7747135171976, 3761.191343861083, 3763.9272807249804, 4971.577415919687, 5224.7126408047925, 5256.185291279858, 5267.209148889396, 5286.867828304323, 5056.191009438472, 5081.441041485507, 5093.974530127326, 5108.770105345194, 5122.257272695364, 4819.783109760139, 5111.667741596784, 5135.614828887774, 5139.331886854685, 5147.948520514747, 4735.313379559876, 4881.8535443936735, 4992.350994370854, 5008.899822003466, 5016.774245159049, 4956.083124621957, 5114.258432527337, 5124.256982737407, 5130.230465430215, 5134.614903882149, 5046.6878611562, 5048.0, 5054.772535235997, 5057.570106540985, 5060.696358218519], "value": 3807.586388917786, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.3544758412963, 727.7525641281966, 736.9963150184249, 745.967239272981, 746.2798399544439, 12870.485180592777, 13223.867761322384, 13251.072424930248, 13271.800922986138, 13344.666383340409, 13200.53798117066, 13709.588712338646, 13736.793948090786, 14107.717845643081, 14220.14679119251, 749.4454103963069, 766.6128605054467, 766.7853001159684, 768.6771555946488, 768.7271018788333, 618.771054709758, 635.1901325809583, 734.6583838515089, 764.9273319034683, 768.2317682317682, 799.168864381046, 803.7628899474669, 806.3508875355336, 808.7007807111377, 809.2716555100427, 797.9082405523382, 798.078219656298, 798.7364169823974, 799.2007992007992, 799.3684988858795, 469.6149157690695, 479.29064983823787, 583.1951906369226, 592.2715060475639, 592.9919137466325, 12884.484620615163, 13340.0, 13447.32763361828, 13919.304034798219, 13942.51201207963, 12486.313252771093, 13377.732445351136, 13431.731365372638, 13462.394192261372, 13741.401654869924, 10827.83758243627, 10868.510917008754, 10970.945145274301, 11316.660500184926, 11348.943255283672, 3273.3289675616443, 3297.0544183724414, 3312.271300313927, 3386.373520898617, 3388.96611033889, 48491.51508484905, 49818.25272620914, 50354.0, 66126.0, 66413.00380494297, 10039.648612298535, 10080.344777589433, 10086.092251697382, 10100.595976160872, 10126.645567405105, 18504.0, 18508.259669613064, 18529.81470185281, 18534.351297704514, 18545.721814172797, 6915.723371065101, 6930.8613827724175, 6975.511714179958, 7149.0706208192605, 7243.022192004118, 3603.261331427051, 3706.4069748840056, 3716.1267102230954, 3743.6069212732814, 3751.962947502338, 2892.248015515981, 2981.359007813287, 3016.3213277012496, 3019.667836537979, 3029.2578318312153, 503.83625321769836, 504.5156649616336, 509.5745052880812, 515.463917525769, 515.8839261166207, 507.34299082688005, 536.2037374498866, 601.569877537554, 621.5090078837668, 625.1185827982523, 7637.809054773675, 7994.400419968526, 8141.51150930946, 8241.25828675422, 8352.566435204815, 532.816178418445, 564.5370795947284, 575.8042265629622, 585.1310803456863, 622.1352320274822, 3696.31618150643, 3723.478712980185, 3738.12154143776, 3889.9805500972316, 3921.627445392683, 99472.0, 104322.0, 105049.47475262727, 106060.46969765256, 106233.46883265534, 88147.55926220329, 92822.53588732013, 94133.0, 94227.52886235525, 94491.52754236323, 78116.0, 79184.0, 79948.0, 80338.5813780236, 80588.59705701434, 6770.187577490733, 7084.326988936043, 7259.673314700745, 7264.0, 7322.0, 44738.3289250668, 45087.32369014533, 45100.548994509634, 45314.0, 46129.538704612525, 49471.0, 51372.0, 52126.0, 57570.4330810852, 59849.0, 10532.57869685204, 10809.519305912818, 10977.121830253402, 11019.779604408028, 11120.221584489009, 12166.939165304117, 12311.69220769488, 12361.690957726129, 12652.620421387373, 12675.556355527513, 11251.324920504796, 11354.659360219206, 11402.429878505876, 11756.58851940178, 12171.54743170324, 11260.774784504429, 11634.127440441831, 11929.210618407309, 12134.575289864812, 12216.938915305367, 10915.454227288763, 11432.599859004895, 12395.876041239833, 12439.937800310941, 12445.128840981406, 9346.138351892729, 9904.603815847286, 9943.353682010646, 9963.352382095141, 10140.29017968735, 9089.63641454361, 9220.861687074565, 9666.613335466784, 10219.489025548544, 10278.383297001905], "value": 6516.146081048431, "unit": "score"}], "name": "dromaeo_css", "value": 6232.009255836605, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 116.0, 113.0, 114.0, 115.0, 115.0, 113.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 135.0, 130.0, 175.0, 127.0, 129.0, 134.0, 126.0, 167.0, 132.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 162.0, 163.0, 163.0, 163.0, 163.0, 161.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 115.0, 78.0, 78.0, 77.0, 76.0, 104.0, 77.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 86.0, 84.0, 84.0, 111.0, 84.0, 85.0, 119.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 116.0, 117.0, 118.0, 118.0, 117.0, 121.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 64.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 93.0, 86.0, 84.0, 83.0, 87.0, 83.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 164.0, 146.0, 137.0, 138.0, 140.0, 140.0, 139.0, 183.0, 163.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 177.0, 177.0, 175.0, 176.0, 176.0, 174.0, 173.0, 172.0, 172.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 70.0, 71.0, 71.0, 70.0, 70.0, 73.0, 71.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1562.9, "alertThreshold": 2.0}]} 12:08:43 INFO - Return code: 0 12:08:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:08:43 INFO - # TBPL SUCCESS # 12:08:43 INFO - Running post-action listener: _resource_record_post_action 12:08:43 INFO - [mozharness: 2016-10-04 19:08:43.266765Z] Finished run-tests step (success) 12:08:43 INFO - Running post-run listener: _resource_record_post_run 12:08:43 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 0; Write bytes: 406609920; Read time: 0; Write time: 389864 12:08:43 INFO - TinderboxPrint: CPU usage
13.0% 12:08:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:08:43 INFO - TinderboxPrint: I/O write bytes / time
406,609,920 / 389,864 12:08:43 INFO - TinderboxPrint: CPU idle
8,354.2 (87.0%) 12:08:43 INFO - TinderboxPrint: CPU user
1,201.4 (12.5%) 12:08:43 INFO - TinderboxPrint: Swap in / out
0 / 0 12:08:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99549184; Read time: 0; Write time: 121960 12:08:43 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 289439744; Read time: 0; Write time: 261468 12:08:43 INFO - Running post-run listener: _upload_blobber_files 12:08:43 INFO - Blob upload gear active. 12:08:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:08:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:08:44 INFO - (blobuploader) - INFO - Open directory for files ... 12:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:55 INFO - (blobuploader) - INFO - Done attempting. 12:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:55 INFO - (blobuploader) - INFO - Done attempting. 12:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:08:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:57 INFO - (blobuploader) - INFO - Done attempting. 12:08:57 INFO - (blobuploader) - INFO - Iteration through files over. 12:08:57 INFO - Return code: 0 12:08:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:08:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:08:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1db00da134f74210ccc25ac773ef390b323f8b9586b01f4a9209e37507ee48ba58c74056cd57e0acd11f72e1d7a9c88916b2f219c271d8cec505d14cceb95604", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d70b20b4d1a2e6fbc0bfe9015fdf79557c20703dca71c08eefe17a3ec6c3609659a103659cc0ca009d41f27c7fcc9ffdb04713d78f4a49af3c234ec95c6e4d5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cb673facb6fb2e4d0a7ca431d64fa852cdb1e3081b76de38822ff5f70484462d4b2bf88e069b2a4d31d24bad9058269d38939b96152e7fa9841b829f44d1b5f4"} 12:08:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:08:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:08:57 INFO - Contents: 12:08:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1db00da134f74210ccc25ac773ef390b323f8b9586b01f4a9209e37507ee48ba58c74056cd57e0acd11f72e1d7a9c88916b2f219c271d8cec505d14cceb95604", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d70b20b4d1a2e6fbc0bfe9015fdf79557c20703dca71c08eefe17a3ec6c3609659a103659cc0ca009d41f27c7fcc9ffdb04713d78f4a49af3c234ec95c6e4d5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cb673facb6fb2e4d0a7ca431d64fa852cdb1e3081b76de38822ff5f70484462d4b2bf88e069b2a4d31d24bad9058269d38939b96152e7fa9841b829f44d1b5f4"} 12:08:57 INFO - Running post-run listener: copy_logs_to_upload_dir 12:08:57 INFO - Copying logs to upload dir... 12:08:57 INFO - mkdir: /builds/slave/test/build/upload/logs 12:08:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.025910 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-10-04 12:08:56.771299) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 12:08:56.774675) ========= 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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1db00da134f74210ccc25ac773ef390b323f8b9586b01f4a9209e37507ee48ba58c74056cd57e0acd11f72e1d7a9c88916b2f219c271d8cec505d14cceb95604", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d70b20b4d1a2e6fbc0bfe9015fdf79557c20703dca71c08eefe17a3ec6c3609659a103659cc0ca009d41f27c7fcc9ffdb04713d78f4a49af3c234ec95c6e4d5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cb673facb6fb2e4d0a7ca431d64fa852cdb1e3081b76de38822ff5f70484462d4b2bf88e069b2a4d31d24bad9058269d38939b96152e7fa9841b829f44d1b5f4"} build_url:https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/SubpiexTTzGGVxp0clLOVA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1db00da134f74210ccc25ac773ef390b323f8b9586b01f4a9209e37507ee48ba58c74056cd57e0acd11f72e1d7a9c88916b2f219c271d8cec505d14cceb95604", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d70b20b4d1a2e6fbc0bfe9015fdf79557c20703dca71c08eefe17a3ec6c3609659a103659cc0ca009d41f27c7fcc9ffdb04713d78f4a49af3c234ec95c6e4d5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cb673facb6fb2e4d0a7ca431d64fa852cdb1e3081b76de38822ff5f70484462d4b2bf88e069b2a4d31d24bad9058269d38939b96152e7fa9841b829f44d1b5f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 12:08:56.801267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 12:08:56.801692) ========= 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=af724c981e2a0f424dbabf99000001e7-1475605583.110684-917969257 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 12:08:56.886993) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 12:08:56.887354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 12:08:56.887705) ========= ========= Total master_lag: 0.17 =========